{"level":"warn","ts":"2026-02-12T03:36:37.101464Z","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-12T03:36:37.101912Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-12T03:36:37.102052Z","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-12T03:36:37.102102Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-12T03:36:37.102144Z","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-12T03:36:37.103079Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-02-12T03:36:37.105114Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-12T03:36:37.114847Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.437608ms"} {"level":"info","ts":"2026-02-12T03:36:37.130207Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-02-12T03:36:37.130412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T03:36:37.130481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-02-12T03:36:37.130552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T03:36:37.130604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-02-12T03:36:37.130678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-02-12T03:36:37.140265Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T03:36:37.159539Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T03:36:37.162965Z","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-12T03:36:37.170216Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T03:36:37.171352Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T03:36:37.171559Z","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-12T03:36:37.171657Z","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-12T03:36:37.171708Z","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-12T03:36:37.172385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-02-12T03:36:37.172521Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-12T03:36:37.183431Z","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-12T03:36:37.192809Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T03:36:37.192946Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T03:36:37.18369Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-12T03:36:37.193524Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-12T03:36:37.632388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T03:36:37.632433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T03:36:37.632451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-02-12T03:36:37.632504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-02-12T03:36:37.63251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-12T03:36:37.632518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-02-12T03:36:37.632525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-12T03:36:37.634856Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:36:37.636776Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T03:36:37.636883Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T03:36:37.638937Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-02-12T03:36:37.639271Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:36:37.639338Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:36:37.639361Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:36:37.639376Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T03:36:37.645033Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T03:36:37.652741Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T03:36:37.652761Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T03:36:49.65082Z","caller":"traceutil/trace.go:171","msg":"trace[658233684] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"137.322016ms","start":"2026-02-12T03:36:49.513475Z","end":"2026-02-12T03:36:49.650797Z","steps":["trace[658233684] 'read index received' (duration: 137.15893ms)","trace[658233684] 'applied index is now lower than readState.Index' (duration: 162.296µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:49.651548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.534124ms","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-12T03:36:49.651639Z","caller":"traceutil/trace.go:171","msg":"trace[432247507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:399; }","duration":"138.188769ms","start":"2026-02-12T03:36:49.513433Z","end":"2026-02-12T03:36:49.651622Z","steps":["trace[432247507] 'agreement among raft nodes before linearized reading' (duration: 137.514233ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:49.651996Z","caller":"traceutil/trace.go:171","msg":"trace[1267779460] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"152.16928ms","start":"2026-02-12T03:36:49.499769Z","end":"2026-02-12T03:36:49.651938Z","steps":["trace[1267779460] 'process raft request' (duration: 150.922372ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:54.814355Z","caller":"traceutil/trace.go:171","msg":"trace[882046551] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"192.936035ms","start":"2026-02-12T03:36:54.621397Z","end":"2026-02-12T03:36:54.814333Z","steps":["trace[882046551] 'process raft request' (duration: 192.821021ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:55.921522Z","caller":"traceutil/trace.go:171","msg":"trace[473276925] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"217.304213ms","start":"2026-02-12T03:36:55.704193Z","end":"2026-02-12T03:36:55.921497Z","steps":["trace[473276925] 'read index received' (duration: 217.061853ms)","trace[473276925] 'applied index is now lower than readState.Index' (duration: 241.279µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:36:55.921683Z","caller":"traceutil/trace.go:171","msg":"trace[223847858] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"243.26341ms","start":"2026-02-12T03:36:55.678403Z","end":"2026-02-12T03:36:55.921666Z","steps":["trace[223847858] 'process raft request' (duration: 242.932697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:55.921847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.613668ms","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-12T03:36:55.921917Z","caller":"traceutil/trace.go:171","msg":"trace[1908493259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:449; }","duration":"119.751653ms","start":"2026-02-12T03:36:55.802149Z","end":"2026-02-12T03:36:55.921901Z","steps":["trace[1908493259] 'agreement among raft nodes before linearized reading' (duration: 119.650839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:55.92204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.871034ms","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-12T03:36:55.92206Z","caller":"traceutil/trace.go:171","msg":"trace[1699810789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:449; }","duration":"217.926606ms","start":"2026-02-12T03:36:55.704127Z","end":"2026-02-12T03:36:55.922054Z","steps":["trace[1699810789] 'agreement among raft nodes before linearized reading' (duration: 217.881654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:56.217415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.519823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:36:56.217786Z","caller":"traceutil/trace.go:171","msg":"trace[1857311590] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"290.83407ms","start":"2026-02-12T03:36:55.926932Z","end":"2026-02-12T03:36:56.217766Z","steps":["trace[1857311590] 'process raft request' (duration: 37.373721ms)","trace[1857311590] 'compare' (duration: 252.396009ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:36:56.218336Z","caller":"traceutil/trace.go:171","msg":"trace[130105632] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"291.208364ms","start":"2026-02-12T03:36:55.927101Z","end":"2026-02-12T03:36:56.218309Z","steps":["trace[130105632] 'process raft request' (duration: 290.421924ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:56.218478Z","caller":"traceutil/trace.go:171","msg":"trace[133935467] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:460; }","duration":"202.501211ms","start":"2026-02-12T03:36:56.01597Z","end":"2026-02-12T03:36:56.218471Z","steps":["trace[133935467] 'read index received' (duration: 31.072542ms)","trace[133935467] 'applied index is now lower than readState.Index' (duration: 171.427839ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:56.262102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.904753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:36:56.262178Z","caller":"traceutil/trace.go:171","msg":"trace[2010721042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:451; }","duration":"154.031767ms","start":"2026-02-12T03:36:56.108129Z","end":"2026-02-12T03:36:56.262161Z","steps":["trace[2010721042] 'agreement among raft nodes before linearized reading' (duration: 153.909923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:56.263049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.074475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-12T03:36:56.263239Z","caller":"traceutil/trace.go:171","msg":"trace[220193866] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:451; }","duration":"247.144528ms","start":"2026-02-12T03:36:56.015937Z","end":"2026-02-12T03:36:56.263081Z","steps":["trace[220193866] 'agreement among raft nodes before linearized reading' (duration: 202.586174ms)","trace[220193866] 'range keys from in-memory index tree' (duration: 44.472581ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:36:56.660026Z","caller":"traceutil/trace.go:171","msg":"trace[947480788] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"387.34214ms","start":"2026-02-12T03:36:56.272658Z","end":"2026-02-12T03:36:56.66Z","steps":["trace[947480788] 'process raft request' (duration: 387.174124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:56.660215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:56.272645Z","time spent":"387.457404ms","remote":"127.0.0.1:36222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:36:57.898777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.166995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:36:57.898847Z","caller":"traceutil/trace.go:171","msg":"trace[2130348068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:452; }","duration":"789.277659ms","start":"2026-02-12T03:36:57.109553Z","end":"2026-02-12T03:36:57.898831Z","steps":["trace[2130348068] 'range keys from in-memory index tree' (duration: 789.102222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:57.898907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:57.109536Z","time spent":"789.357402ms","remote":"127.0.0.1:45254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:36:57.899169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082000391s","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-12T03:36:57.899212Z","caller":"traceutil/trace.go:171","msg":"trace[48185212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:452; }","duration":"1.082079234s","start":"2026-02-12T03:36:56.817122Z","end":"2026-02-12T03:36:57.899201Z","steps":["trace[48185212] 'range keys from in-memory index tree' (duration: 1.081911277s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:57.899242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:56.817102Z","time spent":"1.082131916s","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T03:37:00.53643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100328140967386,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T03:37:00.62986Z","caller":"traceutil/trace.go:171","msg":"trace[1435684740] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"649.328817ms","start":"2026-02-12T03:36:59.980511Z","end":"2026-02-12T03:37:00.62984Z","steps":["trace[1435684740] 'process raft request' (duration: 649.214562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:00.630221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:59.980488Z","time spent":"649.649969ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:37:00.946473Z","caller":"traceutil/trace.go:171","msg":"trace[1785705258] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:469; }","duration":"910.494874ms","start":"2026-02-12T03:37:00.035963Z","end":"2026-02-12T03:37:00.946458Z","steps":["trace[1785705258] 'read index received' (duration: 594.370815ms)","trace[1785705258] 'applied index is now lower than readState.Index' (duration: 316.123479ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:00.946551Z","caller":"traceutil/trace.go:171","msg":"trace[1293482845] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"799.341474ms","start":"2026-02-12T03:37:00.147204Z","end":"2026-02-12T03:37:00.946546Z","steps":["trace[1293482845] 'process raft request' (duration: 799.162458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:00.946617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:00.147181Z","time spent":"799.386066ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:37:00.946714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.401196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:00.946768Z","caller":"traceutil/trace.go:171","msg":"trace[1416041801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"835.484149ms","start":"2026-02-12T03:37:00.111271Z","end":"2026-02-12T03:37:00.946755Z","steps":["trace[1416041801] 'agreement among raft nodes before linearized reading' (duration: 835.398386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:00.946794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:00.11126Z","time spent":"835.529251ms","remote":"127.0.0.1:45254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:37:00.946924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.984612ms","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-12T03:37:00.946944Z","caller":"traceutil/trace.go:171","msg":"trace[1079407382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:458; }","duration":"911.045865ms","start":"2026-02-12T03:37:00.035893Z","end":"2026-02-12T03:37:00.946938Z","steps":["trace[1079407382] 'agreement among raft nodes before linearized reading' (duration: 911.014854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:00.946942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.511354ms","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-12T03:37:00.946982Z","caller":"traceutil/trace.go:171","msg":"trace[637132401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:458; }","duration":"710.563906ms","start":"2026-02-12T03:37:00.236406Z","end":"2026-02-12T03:37:00.94697Z","steps":["trace[637132401] 'agreement among raft nodes before linearized reading' (duration: 710.512504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:00.947017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:00.236394Z","time spent":"710.613938ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:37:00.94703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.32512ms","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-12T03:37:00.947046Z","caller":"traceutil/trace.go:171","msg":"trace[69459343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:458; }","duration":"714.381811ms","start":"2026-02-12T03:37:00.23266Z","end":"2026-02-12T03:37:00.947042Z","steps":["trace[69459343] 'agreement among raft nodes before linearized reading' (duration: 714.35125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:00.947081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:00.232645Z","time spent":"714.432044ms","remote":"127.0.0.1:36300","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-12T03:37:00.946958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:00.035873Z","time spent":"911.082596ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T03:37:06.286095Z","caller":"traceutil/trace.go:171","msg":"trace[467300863] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"195.802048ms","start":"2026-02-12T03:37:06.090274Z","end":"2026-02-12T03:37:06.286076Z","steps":["trace[467300863] 'process raft request' (duration: 195.598771ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:06.286631Z","caller":"traceutil/trace.go:171","msg":"trace[241529253] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:485; }","duration":"177.226235ms","start":"2026-02-12T03:37:06.109383Z","end":"2026-02-12T03:37:06.286609Z","steps":["trace[241529253] 'read index received' (duration: 177.213525ms)","trace[241529253] 'applied index is now lower than readState.Index' (duration: 11.41µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:06.28683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.423133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:06.286893Z","caller":"traceutil/trace.go:171","msg":"trace[614474694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"177.528342ms","start":"2026-02-12T03:37:06.109355Z","end":"2026-02-12T03:37:06.286883Z","steps":["trace[614474694] 'agreement among raft nodes before linearized reading' (duration: 177.330794ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:07.407676Z","caller":"traceutil/trace.go:171","msg":"trace[95589693] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"424.079034ms","start":"2026-02-12T03:37:06.983578Z","end":"2026-02-12T03:37:07.407658Z","steps":["trace[95589693] 'read index received' (duration: 423.939796ms)","trace[95589693] 'applied index is now lower than readState.Index' (duration: 138.628µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:07.40791Z","caller":"traceutil/trace.go:171","msg":"trace[1980125719] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"429.587851ms","start":"2026-02-12T03:37:06.978314Z","end":"2026-02-12T03:37:07.407902Z","steps":["trace[1980125719] 'process raft request' (duration: 429.249065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:07.407982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:06.978302Z","time spent":"429.628421ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:37:07.408116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.534038ms","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-12T03:37:07.408139Z","caller":"traceutil/trace.go:171","msg":"trace[1855011832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:506; }","duration":"424.572898ms","start":"2026-02-12T03:37:06.983558Z","end":"2026-02-12T03:37:07.408131Z","steps":["trace[1855011832] 'agreement among raft nodes before linearized reading' (duration: 424.532029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:07.408166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:06.98355Z","time spent":"424.606457ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:37:07.408242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.157627ms","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-12T03:37:07.408261Z","caller":"traceutil/trace.go:171","msg":"trace[1987559428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:506; }","duration":"417.191606ms","start":"2026-02-12T03:37:06.991062Z","end":"2026-02-12T03:37:07.408254Z","steps":["trace[1987559428] 'agreement among raft nodes before linearized reading' (duration: 417.142116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:07.408278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:06.991053Z","time spent":"417.220305ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T03:37:09.92499Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100328140967503,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T03:37:10.213231Z","caller":"traceutil/trace.go:171","msg":"trace[854526629] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"788.548207ms","start":"2026-02-12T03:37:09.424667Z","end":"2026-02-12T03:37:10.213215Z","steps":["trace[854526629] 'read index received' (duration: 788.388269ms)","trace[854526629] 'applied index is now lower than readState.Index' (duration: 159.308µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:10.213467Z","caller":"traceutil/trace.go:171","msg":"trace[519217718] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"797.963591ms","start":"2026-02-12T03:37:09.415494Z","end":"2026-02-12T03:37:10.213458Z","steps":["trace[519217718] 'process raft request' (duration: 797.615615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:10.213586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:09.415465Z","time spent":"798.055781ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:37:10.213784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.114101ms","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-12T03:37:10.213807Z","caller":"traceutil/trace.go:171","msg":"trace[1151768035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"789.156341ms","start":"2026-02-12T03:37:09.424642Z","end":"2026-02-12T03:37:10.213799Z","steps":["trace[1151768035] 'agreement among raft nodes before linearized reading' (duration: 789.060622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:10.21384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:09.424633Z","time spent":"789.201431ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T03:37:10.213934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.711796ms","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-12T03:37:10.213955Z","caller":"traceutil/trace.go:171","msg":"trace[705064207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"788.742795ms","start":"2026-02-12T03:37:09.425205Z","end":"2026-02-12T03:37:10.213948Z","steps":["trace[705064207] 'agreement among raft nodes before linearized reading' (duration: 788.708296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:10.214067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:09.425198Z","time spent":"788.769375ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:37:10.214195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.881589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:10.21427Z","caller":"traceutil/trace.go:171","msg":"trace[896508879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"105.090437ms","start":"2026-02-12T03:37:10.109117Z","end":"2026-02-12T03:37:10.214207Z","steps":["trace[896508879] 'agreement among raft nodes before linearized reading' (duration: 105.062827ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:11.335132Z","caller":"traceutil/trace.go:171","msg":"trace[983513635] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"116.535535ms","start":"2026-02-12T03:37:11.218576Z","end":"2026-02-12T03:37:11.335111Z","steps":["trace[983513635] 'read index received' (duration: 116.355836ms)","trace[983513635] 'applied index is now lower than readState.Index' (duration: 178.819µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:11.335281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.697193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:11.335313Z","caller":"traceutil/trace.go:171","msg":"trace[2111696530] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:517; }","duration":"116.748843ms","start":"2026-02-12T03:37:11.218555Z","end":"2026-02-12T03:37:11.335304Z","steps":["trace[2111696530] 'agreement among raft nodes before linearized reading' (duration: 116.648234ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:11.335627Z","caller":"traceutil/trace.go:171","msg":"trace[1350638841] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"310.792225ms","start":"2026-02-12T03:37:11.024824Z","end":"2026-02-12T03:37:11.335616Z","steps":["trace[1350638841] 'process raft request' (duration: 310.160211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:11.3357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:11.024809Z","time spent":"310.842194ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:37:13.207455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100328140967563,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:37:13.664834Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.387587944s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T03:37:13.711717Z","caller":"traceutil/trace.go:171","msg":"trace[2073662822] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.434505063s","start":"2026-02-12T03:37:12.277191Z","end":"2026-02-12T03:37:13.711696Z","steps":["trace[2073662822] 'process raft request' (duration: 1.387758832s)","trace[2073662822] 'compare' (duration: 46.325715ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:13.711844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:12.27718Z","time spent":"1.434588853s","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:37:13.711969Z","caller":"traceutil/trace.go:171","msg":"trace[1716408911] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.433376504s","start":"2026-02-12T03:37:12.278577Z","end":"2026-02-12T03:37:13.711954Z","steps":["trace[1716408911] 'process raft request' (duration: 1.433086497s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:13.712091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:12.27857Z","time spent":"1.433459844s","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:37:13.71214Z","caller":"traceutil/trace.go:171","msg":"trace[2105616139] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:555; }","duration":"1.005016083s","start":"2026-02-12T03:37:12.707114Z","end":"2026-02-12T03:37:13.71213Z","steps":["trace[2105616139] 'read index received' (duration: 957.843575ms)","trace[2105616139] 'applied index is now lower than readState.Index' (duration: 47.171868ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:13.712255Z","caller":"traceutil/trace.go:171","msg":"trace[1785036162] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"533.404185ms","start":"2026-02-12T03:37:13.178845Z","end":"2026-02-12T03:37:13.712249Z","steps":["trace[1785036162] 'process raft request' (duration: 533.046198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:13.712301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:13.178829Z","time spent":"533.445394ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:37:13.712392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.034081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:13.712427Z","caller":"traceutil/trace.go:171","msg":"trace[1794007747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"603.097121ms","start":"2026-02-12T03:37:13.109322Z","end":"2026-02-12T03:37:13.712419Z","steps":["trace[1794007747] 'agreement among raft nodes before linearized reading' (duration: 603.037642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:13.71248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:13.109306Z","time spent":"603.168771ms","remote":"127.0.0.1:45254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:37:13.712476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00536502s","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-12T03:37:13.71268Z","caller":"traceutil/trace.go:171","msg":"trace[1793437243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:543; }","duration":"1.005586128s","start":"2026-02-12T03:37:12.707075Z","end":"2026-02-12T03:37:13.712661Z","steps":["trace[1793437243] 'agreement among raft nodes before linearized reading' (duration: 1.00537152s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:13.712714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:12.707058Z","time spent":"1.005648058s","remote":"127.0.0.1:36300","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-12T03:37:19.195084Z","caller":"traceutil/trace.go:171","msg":"trace[209054506] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"358.139567ms","start":"2026-02-12T03:37:18.836929Z","end":"2026-02-12T03:37:19.195068Z","steps":["trace[209054506] 'read index received' (duration: 358.029877ms)","trace[209054506] 'applied index is now lower than readState.Index' (duration: 108.92µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:19.19522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.271076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tsm7l\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-12T03:37:19.195237Z","caller":"traceutil/trace.go:171","msg":"trace[53888426] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tsm7l; range_end:; response_count:1; response_revision:556; }","duration":"358.329796ms","start":"2026-02-12T03:37:18.836902Z","end":"2026-02-12T03:37:19.195231Z","steps":["trace[53888426] 'agreement among raft nodes before linearized reading' (duration: 358.229777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:19.195256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:18.836888Z","time spent":"358.364446ms","remote":"127.0.0.1:36222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-tsm7l\" "} {"level":"info","ts":"2026-02-12T03:37:19.19539Z","caller":"traceutil/trace.go:171","msg":"trace[384944193] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"846.533636ms","start":"2026-02-12T03:37:18.348829Z","end":"2026-02-12T03:37:19.195363Z","steps":["trace[384944193] 'process raft request' (duration: 846.134137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:19.195532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:18.34882Z","time spent":"846.626895ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:37:19.449034Z","caller":"traceutil/trace.go:171","msg":"trace[1423216294] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"239.997979ms","start":"2026-02-12T03:37:19.209009Z","end":"2026-02-12T03:37:19.449007Z","steps":["trace[1423216294] 'process raft request' (duration: 239.87647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:22.609497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100328140967610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:37:22.642332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.772320907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T03:37:22.64239Z","caller":"traceutil/trace.go:171","msg":"trace[429064005] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:557; }","duration":"1.772417856s","start":"2026-02-12T03:37:20.869959Z","end":"2026-02-12T03:37:22.642377Z","steps":["trace[429064005] 'range keys from in-memory index tree' (duration: 1.772203498s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:22.642417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:20.869942Z","time spent":"1.772470026s","remote":"127.0.0.1:45282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"warn","ts":"2026-02-12T03:37:22.642529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.906416552s","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-12T03:37:22.642595Z","caller":"traceutil/trace.go:171","msg":"trace[1824890165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"2.906517012s","start":"2026-02-12T03:37:19.73606Z","end":"2026-02-12T03:37:22.642577Z","steps":["trace[1824890165] 'range keys from in-memory index tree' (duration: 2.906284512s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:22.642635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:19.736045Z","time spent":"2.906580632s","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T03:37:22.642726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.442903448s","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-12T03:37:22.64275Z","caller":"traceutil/trace.go:171","msg":"trace[1488655157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"1.442946488s","start":"2026-02-12T03:37:21.199795Z","end":"2026-02-12T03:37:22.642742Z","steps":["trace[1488655157] 'range keys from in-memory index tree' (duration: 1.442808318s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:22.642768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:21.19978Z","time spent":"1.442983348s","remote":"127.0.0.1:36300","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-12T03:37:22.642869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.9007546s","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-12T03:37:22.642901Z","caller":"traceutil/trace.go:171","msg":"trace[127461436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"2.9008074s","start":"2026-02-12T03:37:19.742083Z","end":"2026-02-12T03:37:22.64289Z","steps":["trace[127461436] 'range keys from in-memory index tree' (duration: 2.90064722s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:22.642945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:19.742071Z","time spent":"2.90086696s","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:37:22.64312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.898280502s","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-12T03:37:22.643148Z","caller":"traceutil/trace.go:171","msg":"trace[833768688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"2.898308572s","start":"2026-02-12T03:37:19.744831Z","end":"2026-02-12T03:37:22.64314Z","steps":["trace[833768688] 'range keys from in-memory index tree' (duration: 2.898196352s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:22.643178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:19.744797Z","time spent":"2.898370362s","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T03:37:22.643612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.534760541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:22.643643Z","caller":"traceutil/trace.go:171","msg":"trace[428924501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"2.534813121s","start":"2026-02-12T03:37:20.108821Z","end":"2026-02-12T03:37:22.643634Z","steps":["trace[428924501] 'range keys from in-memory index tree' (duration: 2.534714471s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:22.643661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:20.108807Z","time spent":"2.534850191s","remote":"127.0.0.1:45254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:37:22.759148Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.249446158s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T03:37:22.759483Z","caller":"traceutil/trace.go:171","msg":"trace[1889565084] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.249849886s","start":"2026-02-12T03:37:21.50962Z","end":"2026-02-12T03:37:22.75947Z","steps":["trace[1889565084] 'process raft request' (duration: 1.249760266s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:22.75956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:21.509603Z","time spent":"1.249909376s","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:37:22.759891Z","caller":"traceutil/trace.go:171","msg":"trace[1734625089] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:574; }","duration":"650.671407ms","start":"2026-02-12T03:37:22.109213Z","end":"2026-02-12T03:37:22.759884Z","steps":["trace[1734625089] 'read index received' (duration: 650.669307ms)","trace[1734625089] 'applied index is now lower than readState.Index' (duration: 1.75µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:22.759929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.727506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:22.759944Z","caller":"traceutil/trace.go:171","msg":"trace[910066049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"650.775576ms","start":"2026-02-12T03:37:22.109163Z","end":"2026-02-12T03:37:22.759939Z","steps":["trace[910066049] 'agreement among raft nodes before linearized reading' (duration: 650.750856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:22.759957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:22.109125Z","time spent":"650.829426ms","remote":"127.0.0.1:45260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T03:37:22.786352Z","caller":"traceutil/trace.go:171","msg":"trace[923170357] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"133.141604ms","start":"2026-02-12T03:37:22.65319Z","end":"2026-02-12T03:37:22.786332Z","steps":["trace[923170357] 'process raft request' (duration: 133.097004ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:22.786528Z","caller":"traceutil/trace.go:171","msg":"trace[1375659757] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"135.774886ms","start":"2026-02-12T03:37:22.650734Z","end":"2026-02-12T03:37:22.786509Z","steps":["trace[1375659757] 'process raft request' (duration: 135.442207ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:22.786617Z","caller":"traceutil/trace.go:171","msg":"trace[1707412844] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"134.838259ms","start":"2026-02-12T03:37:22.651767Z","end":"2026-02-12T03:37:22.786606Z","steps":["trace[1707412844] 'process raft request' (duration: 134.47593ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:22.786681Z","caller":"traceutil/trace.go:171","msg":"trace[2128377579] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"134.306821ms","start":"2026-02-12T03:37:22.652369Z","end":"2026-02-12T03:37:22.786676Z","steps":["trace[2128377579] 'process raft request' (duration: 133.898052ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:24.239353Z","caller":"traceutil/trace.go:171","msg":"trace[207230872] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"311.783938ms","start":"2026-02-12T03:37:23.927551Z","end":"2026-02-12T03:37:24.239335Z","steps":["trace[207230872] 'process raft request' (duration: 311.688138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:24.239467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:23.92753Z","time spent":"311.884718ms","remote":"127.0.0.1:36222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17156,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:37:24.591502Z","caller":"traceutil/trace.go:171","msg":"trace[614653983] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"483.876211ms","start":"2026-02-12T03:37:24.107608Z","end":"2026-02-12T03:37:24.591484Z","steps":["trace[614653983] 'read index received' (duration: 132.295312ms)","trace[614653983] 'applied index is now lower than readState.Index' (duration: 351.580249ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:24.591679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.04818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:24.591709Z","caller":"traceutil/trace.go:171","msg":"trace[241350924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"484.11985ms","start":"2026-02-12T03:37:24.107573Z","end":"2026-02-12T03:37:24.591693Z","steps":["trace[241350924] 'agreement among raft nodes before linearized reading' (duration: 483.96905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:24.591733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:24.107556Z","time spent":"484.17266ms","remote":"127.0.0.1:45254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T03:37:24.591815Z","caller":"traceutil/trace.go:171","msg":"trace[854306929] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"663.929136ms","start":"2026-02-12T03:37:23.927851Z","end":"2026-02-12T03:37:24.59178Z","steps":["trace[854306929] 'process raft request' (duration: 663.528857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:24.59192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:23.927842Z","time spent":"664.016176ms","remote":"127.0.0.1:45326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":754,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:37:24.591841Z","caller":"traceutil/trace.go:171","msg":"trace[1857560560] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"475.267658ms","start":"2026-02-12T03:37:24.116568Z","end":"2026-02-12T03:37:24.591836Z","steps":["trace[1857560560] 'process raft request' (duration: 474.882349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:24.592065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:24.116548Z","time spent":"475.490097ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:37:26.935085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.383321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:26.935141Z","caller":"traceutil/trace.go:171","msg":"trace[1024318404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"822.482111ms","start":"2026-02-12T03:37:26.112647Z","end":"2026-02-12T03:37:26.935129Z","steps":["trace[1024318404] 'range keys from in-memory index tree' (duration: 822.317611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:26.935163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:26.112572Z","time spent":"822.586121ms","remote":"127.0.0.1:45260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:37:26.935284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.391569ms","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-12T03:37:26.935319Z","caller":"traceutil/trace.go:171","msg":"trace[744542924] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:599; }","duration":"658.429569ms","start":"2026-02-12T03:37:26.276885Z","end":"2026-02-12T03:37:26.935314Z","steps":["trace[744542924] 'range keys from in-memory index tree' (duration: 658.385339ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:26.944256Z","caller":"traceutil/trace.go:171","msg":"trace[1444846349] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"732.301748ms","start":"2026-02-12T03:37:26.211943Z","end":"2026-02-12T03:37:26.944245Z","steps":["trace[1444846349] 'process raft request' (duration: 732.219308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:26.944335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:26.211871Z","time spent":"732.427698ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:37:26.96019Z","caller":"traceutil/trace.go:171","msg":"trace[1580203299] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:617; }","duration":"158.649098ms","start":"2026-02-12T03:37:26.801527Z","end":"2026-02-12T03:37:26.960176Z","steps":["trace[1580203299] 'read index received' (duration: 143.008943ms)","trace[1580203299] 'applied index is now lower than readState.Index' (duration: 15.638365ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:26.960256Z","caller":"traceutil/trace.go:171","msg":"trace[443484716] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"546.574896ms","start":"2026-02-12T03:37:26.413676Z","end":"2026-02-12T03:37:26.960251Z","steps":["trace[443484716] 'process raft request' (duration: 546.386266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:26.960312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:26.413649Z","time spent":"546.621726ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:37:26.960393Z","caller":"traceutil/trace.go:171","msg":"trace[999976241] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"346.243738ms","start":"2026-02-12T03:37:26.614146Z","end":"2026-02-12T03:37:26.960389Z","steps":["trace[999976241] 'process raft request' (duration: 345.983048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:26.960417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:26.614119Z","time spent":"346.287388ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:37:26.960458Z","caller":"traceutil/trace.go:171","msg":"trace[174852156] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"146.122951ms","start":"2026-02-12T03:37:26.814331Z","end":"2026-02-12T03:37:26.960454Z","steps":["trace[174852156] 'process raft request' (duration: 145.819911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:26.960569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.038078ms","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-12T03:37:26.960603Z","caller":"traceutil/trace.go:171","msg":"trace[1594584661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"159.096837ms","start":"2026-02-12T03:37:26.801502Z","end":"2026-02-12T03:37:26.960599Z","steps":["trace[1594584661] 'agreement among raft nodes before linearized reading' (duration: 158.986308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:26.964258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.481971ms","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-12T03:37:26.964286Z","caller":"traceutil/trace.go:171","msg":"trace[1900527046] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"155.529171ms","start":"2026-02-12T03:37:26.80875Z","end":"2026-02-12T03:37:26.964279Z","steps":["trace[1900527046] 'agreement among raft nodes before linearized reading' (duration: 155.437121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:26.970408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.775916ms","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-12T03:37:26.970449Z","caller":"traceutil/trace.go:171","msg":"trace[1111560534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"160.843056ms","start":"2026-02-12T03:37:26.809597Z","end":"2026-02-12T03:37:26.97044Z","steps":["trace[1111560534] 'agreement among raft nodes before linearized reading' (duration: 160.698186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:26.971409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.061465ms","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-12T03:37:26.971492Z","caller":"traceutil/trace.go:171","msg":"trace[765875153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"152.494794ms","start":"2026-02-12T03:37:26.818984Z","end":"2026-02-12T03:37:26.971479Z","steps":["trace[765875153] 'agreement among raft nodes before linearized reading' (duration: 151.058615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:29.355247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.833594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-12T03:37:29.355405Z","caller":"traceutil/trace.go:171","msg":"trace[102813396] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:638; }","duration":"144.013164ms","start":"2026-02-12T03:37:29.211349Z","end":"2026-02-12T03:37:29.355362Z","steps":["trace[102813396] 'range keys from in-memory index tree' (duration: 143.607873ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:40.323777Z","caller":"traceutil/trace.go:171","msg":"trace[1168065229] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"386.494697ms","start":"2026-02-12T03:37:39.937258Z","end":"2026-02-12T03:37:40.323753Z","steps":["trace[1168065229] 'process raft request' (duration: 386.321296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:40.323937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:39.93725Z","time spent":"386.600187ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:37:40.324727Z","caller":"traceutil/trace.go:171","msg":"trace[2028599363] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:773; }","duration":"307.49962ms","start":"2026-02-12T03:37:40.017214Z","end":"2026-02-12T03:37:40.324714Z","steps":["trace[2028599363] 'read index received' (duration: 306.408874ms)","trace[2028599363] 'applied index is now lower than readState.Index' (duration: 1.090106ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:40.324789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:40.01686Z","time spent":"307.926563ms","remote":"127.0.0.1:45326","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T03:37:40.324973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.744352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-z8p9x\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-02-12T03:37:40.324992Z","caller":"traceutil/trace.go:171","msg":"trace[1580213363] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-z8p9x; range_end:; response_count:1; response_revision:752; }","duration":"307.785062ms","start":"2026-02-12T03:37:40.017199Z","end":"2026-02-12T03:37:40.324984Z","steps":["trace[1580213363] 'agreement among raft nodes before linearized reading' (duration: 307.694801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:40.325009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:40.017193Z","time spent":"307.810902ms","remote":"127.0.0.1:36222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4846,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-z8p9x\" "} {"level":"warn","ts":"2026-02-12T03:37:40.325098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.780699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:40.325111Z","caller":"traceutil/trace.go:171","msg":"trace[23305223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"216.8271ms","start":"2026-02-12T03:37:40.10828Z","end":"2026-02-12T03:37:40.325107Z","steps":["trace[23305223] 'agreement among raft nodes before linearized reading' (duration: 216.80625ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:45.379972Z","caller":"traceutil/trace.go:171","msg":"trace[2126403701] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"210.028257ms","start":"2026-02-12T03:37:45.169917Z","end":"2026-02-12T03:37:45.379945Z","steps":["trace[2126403701] 'read index received' (duration: 196.446283ms)","trace[2126403701] 'applied index is now lower than readState.Index' (duration: 13.580674ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:45.380089Z","caller":"traceutil/trace.go:171","msg":"trace[1460034461] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"280.069792ms","start":"2026-02-12T03:37:45.100004Z","end":"2026-02-12T03:37:45.380074Z","steps":["trace[1460034461] 'process raft request' (duration: 266.296447ms)","trace[1460034461] 'compare' (duration: 13.286211ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:45.380462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.303724ms","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-12T03:37:45.380522Z","caller":"traceutil/trace.go:171","msg":"trace[115035705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"212.373604ms","start":"2026-02-12T03:37:45.168134Z","end":"2026-02-12T03:37:45.380507Z","steps":["trace[115035705] 'agreement among raft nodes before linearized reading' (duration: 212.165673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:45.380842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.003148ms","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-12T03:37:45.380885Z","caller":"traceutil/trace.go:171","msg":"trace[292607264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:772; }","duration":"218.081398ms","start":"2026-02-12T03:37:45.162789Z","end":"2026-02-12T03:37:45.380871Z","steps":["trace[292607264] 'agreement among raft nodes before linearized reading' (duration: 217.958117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:45.381057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.549376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:45.381093Z","caller":"traceutil/trace.go:171","msg":"trace[1835796276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"267.621408ms","start":"2026-02-12T03:37:45.113461Z","end":"2026-02-12T03:37:45.381083Z","steps":["trace[1835796276] 'agreement among raft nodes before linearized reading' (duration: 267.568637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:45.382849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.979461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1000"} {"level":"info","ts":"2026-02-12T03:37:45.382917Z","caller":"traceutil/trace.go:171","msg":"trace[1085496224] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:773; }","duration":"178.070073ms","start":"2026-02-12T03:37:45.20483Z","end":"2026-02-12T03:37:45.3829Z","steps":["trace[1085496224] 'agreement among raft nodes before linearized reading' (duration: 177.942022ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:45.384794Z","caller":"traceutil/trace.go:171","msg":"trace[1687151059] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"211.157805ms","start":"2026-02-12T03:37:45.173623Z","end":"2026-02-12T03:37:45.384781Z","steps":["trace[1687151059] 'process raft request' (duration: 209.052289ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:49.551246Z","caller":"traceutil/trace.go:171","msg":"trace[1879737636] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"133.971297ms","start":"2026-02-12T03:37:49.41725Z","end":"2026-02-12T03:37:49.551221Z","steps":["trace[1879737636] 'read index received' (duration: 133.741685ms)","trace[1879737636] 'applied index is now lower than readState.Index' (duration: 228.422µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:49.551381Z","caller":"traceutil/trace.go:171","msg":"trace[418098095] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"144.15769ms","start":"2026-02-12T03:37:49.407214Z","end":"2026-02-12T03:37:49.551371Z","steps":["trace[418098095] 'process raft request' (duration: 143.864277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:49.551558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.263549ms","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-12T03:37:49.551602Z","caller":"traceutil/trace.go:171","msg":"trace[911594249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"134.323439ms","start":"2026-02-12T03:37:49.417268Z","end":"2026-02-12T03:37:49.551591Z","steps":["trace[911594249] 'agreement among raft nodes before linearized reading' (duration: 134.200988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:49.551696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.444561ms","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-12T03:37:49.551709Z","caller":"traceutil/trace.go:171","msg":"trace[1527494435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"134.467951ms","start":"2026-02-12T03:37:49.417237Z","end":"2026-02-12T03:37:49.551704Z","steps":["trace[1527494435] 'agreement among raft nodes before linearized reading' (duration: 134.432791ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:53.944963Z","caller":"traceutil/trace.go:171","msg":"trace[1396009084] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"123.839879ms","start":"2026-02-12T03:37:53.821107Z","end":"2026-02-12T03:37:53.944947Z","steps":["trace[1396009084] 'read index received' (duration: 60.775002ms)","trace[1396009084] 'applied index is now lower than readState.Index' (duration: 63.064377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:53.945088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.96674ms","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-12T03:37:53.945105Z","caller":"traceutil/trace.go:171","msg":"trace[463825638] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:802; }","duration":"124.035271ms","start":"2026-02-12T03:37:53.821065Z","end":"2026-02-12T03:37:53.9451Z","steps":["trace[463825638] 'agreement among raft nodes before linearized reading' (duration: 123.93968ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:53.945269Z","caller":"traceutil/trace.go:171","msg":"trace[1775805030] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"202.52603ms","start":"2026-02-12T03:37:53.742737Z","end":"2026-02-12T03:37:53.945263Z","steps":["trace[1775805030] 'process raft request' (duration: 139.11984ms)","trace[1775805030] 'compare' (duration: 63.025816ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:38:01.910748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.581777ms","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-12T03:38:01.910794Z","caller":"traceutil/trace.go:171","msg":"trace[1756763315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:822; }","duration":"137.671289ms","start":"2026-02-12T03:38:01.773114Z","end":"2026-02-12T03:38:01.910785Z","steps":["trace[1756763315] 'range keys from in-memory index tree' (duration: 137.476036ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:38:08.273296Z","caller":"traceutil/trace.go:171","msg":"trace[1143124732] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:869; }","duration":"164.243018ms","start":"2026-02-12T03:38:08.109027Z","end":"2026-02-12T03:38:08.27327Z","steps":["trace[1143124732] 'read index received' (duration: 142.073572ms)","trace[1143124732] 'applied index is now lower than readState.Index' (duration: 22.168316ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:38:08.273494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.43727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:38:08.273548Z","caller":"traceutil/trace.go:171","msg":"trace[495672006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:841; }","duration":"164.513932ms","start":"2026-02-12T03:38:08.10902Z","end":"2026-02-12T03:38:08.273534Z","steps":["trace[495672006] 'agreement among raft nodes before linearized reading' (duration: 164.353639ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:38:08.273978Z","caller":"traceutil/trace.go:171","msg":"trace[1575769297] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"244.24018ms","start":"2026-02-12T03:38:08.029718Z","end":"2026-02-12T03:38:08.273958Z","steps":["trace[1575769297] 'process raft request' (duration: 221.338193ms)","trace[1575769297] 'compare' (duration: 21.899142ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:38:34.207578Z","caller":"traceutil/trace.go:171","msg":"trace[1279044608] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"122.212886ms","start":"2026-02-12T03:38:34.085337Z","end":"2026-02-12T03:38:34.207549Z","steps":["trace[1279044608] 'process raft request' (duration: 122.079374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:38:34.207601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.013982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:38:34.207832Z","caller":"traceutil/trace.go:171","msg":"trace[705701472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"100.284338ms","start":"2026-02-12T03:38:34.107537Z","end":"2026-02-12T03:38:34.207821Z","steps":["trace[705701472] 'agreement among raft nodes before linearized reading' (duration: 100.015572ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:38:36.161427Z","caller":"traceutil/trace.go:171","msg":"trace[1946158061] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"109.608872ms","start":"2026-02-12T03:38:36.051791Z","end":"2026-02-12T03:38:36.1614Z","steps":["trace[1946158061] 'process raft request' (duration: 91.137759ms)","trace[1946158061] 'compare' (duration: 18.016623ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:38:36.41015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.213454ms","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-12T03:38:36.410535Z","caller":"traceutil/trace.go:171","msg":"trace[1442880580] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:928; }","duration":"133.631903ms","start":"2026-02-12T03:38:36.276879Z","end":"2026-02-12T03:38:36.410511Z","steps":["trace[1442880580] 'range keys from in-memory index tree' (duration: 133.196834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:38:36.410377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.362179ms","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-12T03:38:36.411369Z","caller":"traceutil/trace.go:171","msg":"trace[30979295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:929; }","duration":"167.389302ms","start":"2026-02-12T03:38:36.243964Z","end":"2026-02-12T03:38:36.411353Z","steps":["trace[30979295] 'agreement among raft nodes before linearized reading' (duration: 166.251027ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:38:36.410209Z","caller":"traceutil/trace.go:171","msg":"trace[1134522036] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"166.187856ms","start":"2026-02-12T03:38:36.243992Z","end":"2026-02-12T03:38:36.410179Z","steps":["trace[1134522036] 'read index received' (duration: 131.901826ms)","trace[1134522036] 'applied index is now lower than readState.Index' (duration: 34.28483ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:38:36.410573Z","caller":"traceutil/trace.go:171","msg":"trace[513978560] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"237.84532ms","start":"2026-02-12T03:38:36.172708Z","end":"2026-02-12T03:38:36.410553Z","steps":["trace[513978560] 'process raft request' (duration: 203.259664ms)","trace[513978560] 'compare' (duration: 33.821579ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:38:36.611003Z","caller":"traceutil/trace.go:171","msg":"trace[683948412] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"122.998787ms","start":"2026-02-12T03:38:36.487978Z","end":"2026-02-12T03:38:36.610977Z","steps":["trace[683948412] 'process raft request' (duration: 25.777208ms)","trace[683948412] 'compare' (duration: 97.118786ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:38:37.144401Z","caller":"traceutil/trace.go:171","msg":"trace[913352681] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"227.687468ms","start":"2026-02-12T03:38:36.916687Z","end":"2026-02-12T03:38:37.144375Z","steps":["trace[913352681] 'process raft request' (duration: 148.819147ms)","trace[913352681] 'compare' (duration: 78.752449ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:38:44.037024Z","caller":"traceutil/trace.go:171","msg":"trace[932082185] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"406.286347ms","start":"2026-02-12T03:38:43.630704Z","end":"2026-02-12T03:38:44.03699Z","steps":["trace[932082185] 'process raft request' (duration: 405.50655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:38:44.037652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:38:43.630685Z","time spent":"406.658476ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:38:44.162314Z","caller":"traceutil/trace.go:171","msg":"trace[1182952871] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"121.427402ms","start":"2026-02-12T03:38:44.040863Z","end":"2026-02-12T03:38:44.16229Z","steps":["trace[1182952871] 'process raft request' (duration: 107.283223ms)","trace[1182952871] 'compare' (duration: 13.739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:38:53.297893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.321791ms","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-12T03:38:53.29795Z","caller":"traceutil/trace.go:171","msg":"trace[1995889544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"793.552557ms","start":"2026-02-12T03:38:52.504382Z","end":"2026-02-12T03:38:53.297935Z","steps":["trace[1995889544] 'range keys from in-memory index tree' (duration: 793.131826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:38:53.297989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:38:52.504363Z","time spent":"793.619828ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T03:38:53.29817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.544347ms","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-12T03:38:53.29819Z","caller":"traceutil/trace.go:171","msg":"trace[1749740620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"622.588199ms","start":"2026-02-12T03:38:52.675595Z","end":"2026-02-12T03:38:53.298183Z","steps":["trace[1749740620] 'range keys from in-memory index tree' (duration: 622.448204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:38:53.298206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:38:52.67558Z","time spent":"622.622589ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:38:53.298434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.29875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:38:53.298457Z","caller":"traceutil/trace.go:171","msg":"trace[944202659] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1000; }","duration":"306.365812ms","start":"2026-02-12T03:38:52.992082Z","end":"2026-02-12T03:38:53.298448Z","steps":["trace[944202659] 'count revisions from in-memory index tree' (duration: 306.197828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:38:53.298477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:38:52.992057Z","time spent":"306.413573ms","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-12T03:38:53.298582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.863692ms","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-12T03:38:53.298598Z","caller":"traceutil/trace.go:171","msg":"trace[959245742] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"269.932283ms","start":"2026-02-12T03:38:53.028661Z","end":"2026-02-12T03:38:53.298593Z","steps":["trace[959245742] 'range keys from in-memory index tree' (duration: 269.758859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:38:53.298715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.531458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:38:53.298732Z","caller":"traceutil/trace.go:171","msg":"trace[212105302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1000; }","duration":"190.597761ms","start":"2026-02-12T03:38:53.108129Z","end":"2026-02-12T03:38:53.298727Z","steps":["trace[212105302] 'range keys from in-memory index tree' (duration: 190.484727ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:38:53.451587Z","caller":"traceutil/trace.go:171","msg":"trace[1867255774] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"147.85206ms","start":"2026-02-12T03:38:53.303703Z","end":"2026-02-12T03:38:53.451555Z","steps":["trace[1867255774] 'process raft request' (duration: 147.794718ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:38:53.45202Z","caller":"traceutil/trace.go:171","msg":"trace[1536042422] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"148.492425ms","start":"2026-02-12T03:38:53.303514Z","end":"2026-02-12T03:38:53.452006Z","steps":["trace[1536042422] 'process raft request' (duration: 123.284203ms)","trace[1536042422] 'compare' (duration: 24.602017ms)"],"step_count":2}