{"level":"warn","ts":"2026-02-09T03:04:04.463385Z","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-09T03:04:04.463489Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.41: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.41:2380","--initial-cluster=instance=https://199.204.45.41:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.41:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.41: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-09T03:04:04.463694Z","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-09T03:04:04.463708Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-09T03:04:04.463817Z","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-09T03:04:04.464434Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"]} {"level":"info","ts":"2026-02-09T03:04:04.464565Z","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.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.41: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-09T03:04:04.47046Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.648231ms"} {"level":"info","ts":"2026-02-09T03:04:04.482597Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"17dd22a2661aae79","cluster-id":"c5133f40b27eeb3"} {"level":"info","ts":"2026-02-09T03:04:04.482699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T03:04:04.482751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 0"} {"level":"info","ts":"2026-02-09T03:04:04.482764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 17dd22a2661aae79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T03:04:04.48283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 1"} {"level":"info","ts":"2026-02-09T03:04:04.482892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"warn","ts":"2026-02-09T03:04:04.495897Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T03:04:04.505252Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T03:04:04.508575Z","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-09T03:04:04.516085Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"17dd22a2661aae79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T03:04:04.517528Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"17dd22a2661aae79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T03:04:04.517681Z","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-09T03:04:04.517724Z","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-09T03:04:04.517764Z","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-09T03:04:04.52342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"info","ts":"2026-02-09T03:04:04.523558Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","added-peer-id":"17dd22a2661aae79","added-peer-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-09T03:04:04.524314Z","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-09T03:04:04.524915Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-09T03:04:04.524928Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-09T03:04:04.525534Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"17dd22a2661aae79","initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T03:04:04.525559Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T03:04:04.583459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T03:04:04.583556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T03:04:04.583587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgPreVoteResp from 17dd22a2661aae79 at term 1"} {"level":"info","ts":"2026-02-09T03:04:04.583609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became candidate at term 2"} {"level":"info","ts":"2026-02-09T03:04:04.583659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgVoteResp from 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-09T03:04:04.583682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became leader at term 2"} {"level":"info","ts":"2026-02-09T03:04:04.583712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 17dd22a2661aae79 elected leader 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-09T03:04:04.588418Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"17dd22a2661aae79","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.41:2379]}","request-path":"/0/members/17dd22a2661aae79/attributes","cluster-id":"c5133f40b27eeb3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T03:04:04.588484Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T03:04:04.588796Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:04:04.58905Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T03:04:04.590739Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.41:2379"} {"level":"info","ts":"2026-02-09T03:04:04.5915Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:04:04.591607Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:04:04.595306Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:04:04.596862Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T03:04:04.596877Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T03:04:04.600884Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-09T03:04:17.218668Z","caller":"traceutil/trace.go:171","msg":"trace[208026968] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"120.664899ms","start":"2026-02-09T03:04:17.097978Z","end":"2026-02-09T03:04:17.218643Z","steps":["trace[208026968] 'process raft request' (duration: 120.549887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:21.635616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059391168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4018"} {"level":"info","ts":"2026-02-09T03:04:21.63571Z","caller":"traceutil/trace.go:171","msg":"trace[1942500531] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:369; }","duration":"1.059535191s","start":"2026-02-09T03:04:20.576157Z","end":"2026-02-09T03:04:21.635692Z","steps":["trace[1942500531] 'range keys from in-memory index tree' (duration: 1.059270806s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:21.635772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:20.576141Z","time spent":"1.059621223s","remote":"127.0.0.1:42104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-09T03:04:21.636048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.98718ms","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-09T03:04:21.636104Z","caller":"traceutil/trace.go:171","msg":"trace[882295820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:369; }","duration":"996.054171ms","start":"2026-02-09T03:04:20.640016Z","end":"2026-02-09T03:04:21.63607Z","steps":["trace[882295820] 'range keys from in-memory index tree' (duration: 995.915098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:21.636137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:20.639995Z","time spent":"996.134703ms","remote":"127.0.0.1:42198","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-09T03:04:21.637799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.585742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:04:21.639426Z","caller":"traceutil/trace.go:171","msg":"trace[831541723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:369; }","duration":"860.238607ms","start":"2026-02-09T03:04:20.779174Z","end":"2026-02-09T03:04:21.639413Z","steps":["trace[831541723] 'range keys from in-memory index tree' (duration: 858.49541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:21.639535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:20.779157Z","time spent":"860.365039ms","remote":"127.0.0.1:41912","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-09T03:04:21.639962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.933959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:04:21.640246Z","caller":"traceutil/trace.go:171","msg":"trace[564199476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"749.304117ms","start":"2026-02-09T03:04:20.89093Z","end":"2026-02-09T03:04:21.640234Z","steps":["trace[564199476] 'range keys from in-memory index tree' (duration: 748.871938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:21.640353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:20.890913Z","time spent":"749.426789ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T03:04:22.439877Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251635003328093,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:04:22.940832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251635003328093,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:04:23.113581Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.459977539s","expected-duration":"1s"} {"level":"info","ts":"2026-02-09T03:04:23.114245Z","caller":"traceutil/trace.go:171","msg":"trace[1410025623] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"1.460703255s","start":"2026-02-09T03:04:21.65352Z","end":"2026-02-09T03:04:23.114224Z","steps":["trace[1410025623] 'process raft request' (duration: 1.460262056s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:23.115771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:21.653504Z","time spent":"1.460863488s","remote":"127.0.0.1:42198","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-09T03:04:23.637441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.41825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:04:23.638483Z","caller":"traceutil/trace.go:171","msg":"trace[972103288] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:382; }","duration":"1.707464853s","start":"2026-02-09T03:04:21.931007Z","end":"2026-02-09T03:04:23.638472Z","steps":["trace[972103288] 'read index received' (duration: 1.182925471s)","trace[972103288] 'applied index is now lower than readState.Index' (duration: 524.538302ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:04:23.638779Z","caller":"traceutil/trace.go:171","msg":"trace[2139492928] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"1.983534093s","start":"2026-02-09T03:04:21.654881Z","end":"2026-02-09T03:04:23.638415Z","steps":["trace[2139492928] 'process raft request' (duration: 1.763867949s)","trace[2139492928] 'compare' (duration: 218.315257ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:04:23.63902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:21.654875Z","time spent":"1.984056184s","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:04:23.645274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304170074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-09T03:04:23.646049Z","caller":"traceutil/trace.go:171","msg":"trace[345645321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:371; }","duration":"1.304988671s","start":"2026-02-09T03:04:22.341022Z","end":"2026-02-09T03:04:23.646011Z","steps":["trace[345645321] 'agreement among raft nodes before linearized reading' (duration: 1.304160094s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:23.648107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:22.341007Z","time spent":"1.307089715s","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T03:04:23.647162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.716126304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-09T03:04:23.6459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.098002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-09T03:04:23.645732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.975237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-09T03:04:23.650425Z","caller":"traceutil/trace.go:171","msg":"trace[1583271913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"1.719456834s","start":"2026-02-09T03:04:21.930957Z","end":"2026-02-09T03:04:23.650414Z","steps":["trace[1583271913] 'agreement among raft nodes before linearized reading' (duration: 1.7077854s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:23.650793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:21.93091Z","time spent":"1.719873464s","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-09T03:04:23.650604Z","caller":"traceutil/trace.go:171","msg":"trace[457204647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"872.844261ms","start":"2026-02-09T03:04:22.77775Z","end":"2026-02-09T03:04:23.650594Z","steps":["trace[457204647] 'agreement among raft nodes before linearized reading' (duration: 868.020321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:23.651036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:22.777728Z","time spent":"873.299071ms","remote":"127.0.0.1:41912","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-09T03:04:23.650731Z","caller":"traceutil/trace.go:171","msg":"trace[1536832574] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:371; }","duration":"513.056743ms","start":"2026-02-09T03:04:23.137667Z","end":"2026-02-09T03:04:23.650724Z","steps":["trace[1536832574] 'agreement among raft nodes before linearized reading' (duration: 507.931176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:23.651444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:23.137654Z","time spent":"513.779829ms","remote":"127.0.0.1:42366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4056,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-09T03:04:29.340964Z","caller":"traceutil/trace.go:171","msg":"trace[337876156] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"195.628536ms","start":"2026-02-09T03:04:29.145319Z","end":"2026-02-09T03:04:29.340947Z","steps":["trace[337876156] 'process raft request' (duration: 195.537854ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:29.903965Z","caller":"traceutil/trace.go:171","msg":"trace[1267490581] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:433; }","duration":"127.492985ms","start":"2026-02-09T03:04:29.776451Z","end":"2026-02-09T03:04:29.903944Z","steps":["trace[1267490581] 'read index received' (duration: 28.782321ms)","trace[1267490581] 'applied index is now lower than readState.Index' (duration: 98.709684ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:04:29.904097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.663538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:04:29.904147Z","caller":"traceutil/trace.go:171","msg":"trace[558181851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"127.769791ms","start":"2026-02-09T03:04:29.776365Z","end":"2026-02-09T03:04:29.904135Z","steps":["trace[558181851] 'agreement among raft nodes before linearized reading' (duration: 127.668519ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:29.90462Z","caller":"traceutil/trace.go:171","msg":"trace[1148769837] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"194.93732ms","start":"2026-02-09T03:04:29.709652Z","end":"2026-02-09T03:04:29.904589Z","steps":["trace[1148769837] 'process raft request' (duration: 194.251635ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:29.905381Z","caller":"traceutil/trace.go:171","msg":"trace[293047227] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"194.948061ms","start":"2026-02-09T03:04:29.709567Z","end":"2026-02-09T03:04:29.904515Z","steps":["trace[293047227] 'process raft request' (duration: 95.659144ms)","trace[293047227] 'compare' (duration: 98.566341ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:04:34.115608Z","caller":"traceutil/trace.go:171","msg":"trace[1936451255] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"185.036098ms","start":"2026-02-09T03:04:33.930562Z","end":"2026-02-09T03:04:34.115598Z","steps":["trace[1936451255] 'process raft request' (duration: 184.733701ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:34.115422Z","caller":"traceutil/trace.go:171","msg":"trace[345432466] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"182.893876ms","start":"2026-02-09T03:04:33.932509Z","end":"2026-02-09T03:04:34.115403Z","steps":["trace[345432466] 'read index received' (duration: 182.701902ms)","trace[345432466] 'applied index is now lower than readState.Index' (duration: 191.314µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:04:34.115958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.448406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12677"} {"level":"info","ts":"2026-02-09T03:04:34.115988Z","caller":"traceutil/trace.go:171","msg":"trace[1837902971] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:427; }","duration":"106.515628ms","start":"2026-02-09T03:04:34.009465Z","end":"2026-02-09T03:04:34.11598Z","steps":["trace[1837902971] 'agreement among raft nodes before linearized reading' (duration: 106.443506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:34.116223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.709466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-09T03:04:34.116249Z","caller":"traceutil/trace.go:171","msg":"trace[1347548840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:427; }","duration":"183.760027ms","start":"2026-02-09T03:04:33.932482Z","end":"2026-02-09T03:04:34.116242Z","steps":["trace[1347548840] 'agreement among raft nodes before linearized reading' (duration: 183.389198ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:35.091459Z","caller":"traceutil/trace.go:171","msg":"trace[1471712882] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"134.604482ms","start":"2026-02-09T03:04:34.956828Z","end":"2026-02-09T03:04:35.091432Z","steps":["trace[1471712882] 'process raft request' (duration: 134.332536ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:35.44339Z","caller":"traceutil/trace.go:171","msg":"trace[184753106] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"170.905081ms","start":"2026-02-09T03:04:35.272458Z","end":"2026-02-09T03:04:35.443363Z","steps":["trace[184753106] 'process raft request' (duration: 117.229904ms)","trace[184753106] 'compare' (duration: 53.502453ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:04:35.44616Z","caller":"traceutil/trace.go:171","msg":"trace[1314974370] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"170.47533ms","start":"2026-02-09T03:04:35.275669Z","end":"2026-02-09T03:04:35.446145Z","steps":["trace[1314974370] 'process raft request' (duration: 170.06956ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:35.446651Z","caller":"traceutil/trace.go:171","msg":"trace[331813289] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"173.418612ms","start":"2026-02-09T03:04:35.273215Z","end":"2026-02-09T03:04:35.446633Z","steps":["trace[331813289] 'process raft request' (duration: 172.445129ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:35.447283Z","caller":"traceutil/trace.go:171","msg":"trace[1758263192] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"171.479764ms","start":"2026-02-09T03:04:35.275763Z","end":"2026-02-09T03:04:35.447243Z","steps":["trace[1758263192] 'process raft request' (duration: 170.04026ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:35.448187Z","caller":"traceutil/trace.go:171","msg":"trace[1788924758] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"131.200341ms","start":"2026-02-09T03:04:35.316973Z","end":"2026-02-09T03:04:35.448173Z","steps":["trace[1788924758] 'process raft request' (duration: 130.655588ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:35.448451Z","caller":"traceutil/trace.go:171","msg":"trace[1459747764] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"172.737156ms","start":"2026-02-09T03:04:35.275703Z","end":"2026-02-09T03:04:35.44844Z","steps":["trace[1459747764] 'process raft request' (duration: 170.073381ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:35.448835Z","caller":"traceutil/trace.go:171","msg":"trace[480258787] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"169.135768ms","start":"2026-02-09T03:04:35.279686Z","end":"2026-02-09T03:04:35.448822Z","steps":["trace[480258787] 'process raft request' (duration: 166.148726ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:39.501648Z","caller":"traceutil/trace.go:171","msg":"trace[1250588216] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"156.00157ms","start":"2026-02-09T03:04:39.345604Z","end":"2026-02-09T03:04:39.501606Z","steps":["trace[1250588216] 'process raft request' (duration: 155.853696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:40.51914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251635003328352,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:04:41.019351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251635003328352,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:04:41.033511Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.088809049s","expected-duration":"1s"} {"level":"info","ts":"2026-02-09T03:04:41.033864Z","caller":"traceutil/trace.go:171","msg":"trace[378624346] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.089227398s","start":"2026-02-09T03:04:39.94462Z","end":"2026-02-09T03:04:41.033847Z","steps":["trace[378624346] 'process raft request' (duration: 1.089113775s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:41.033929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:39.944604Z","time spent":"1.08928289s","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T03:04:41.038655Z","caller":"traceutil/trace.go:171","msg":"trace[171081054] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:526; }","duration":"1.020124081s","start":"2026-02-09T03:04:40.018515Z","end":"2026-02-09T03:04:41.038639Z","steps":["trace[171081054] 'read index received' (duration: 1.015182565s)","trace[171081054] 'applied index is now lower than readState.Index' (duration: 4.940536ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:04:41.038893Z","caller":"traceutil/trace.go:171","msg":"trace[966734172] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"577.52989ms","start":"2026-02-09T03:04:40.461357Z","end":"2026-02-09T03:04:41.038887Z","steps":["trace[966734172] 'process raft request' (duration: 577.14016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:41.039278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:40.461322Z","time spent":"577.630474ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:04:41.03935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020831149s","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-09T03:04:41.039399Z","caller":"traceutil/trace.go:171","msg":"trace[942255954] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:511; }","duration":"1.020906032s","start":"2026-02-09T03:04:40.018483Z","end":"2026-02-09T03:04:41.039389Z","steps":["trace[942255954] 'agreement among raft nodes before linearized reading' (duration: 1.02083261s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:41.039428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:40.018466Z","time spent":"1.020954343s","remote":"127.0.0.1:41988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-09T03:04:41.039597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.92915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-09T03:04:41.039658Z","caller":"traceutil/trace.go:171","msg":"trace[1197305414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"901.016501ms","start":"2026-02-09T03:04:40.138635Z","end":"2026-02-09T03:04:41.039651Z","steps":["trace[1197305414] 'agreement among raft nodes before linearized reading' (duration: 900.939699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:41.039718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:40.13862Z","time spent":"901.091394ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T03:04:41.039891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.445305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-09T03:04:41.039949Z","caller":"traceutil/trace.go:171","msg":"trace[771285592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:511; }","duration":"483.535298ms","start":"2026-02-09T03:04:40.556408Z","end":"2026-02-09T03:04:41.039944Z","steps":["trace[771285592] 'agreement among raft nodes before linearized reading' (duration: 483.462946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:41.040032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:40.556397Z","time spent":"483.62772ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T03:04:41.040111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.154883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:04:41.04014Z","caller":"traceutil/trace.go:171","msg":"trace[866510055] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:511; }","duration":"897.200085ms","start":"2026-02-09T03:04:40.142932Z","end":"2026-02-09T03:04:41.040132Z","steps":["trace[866510055] 'agreement among raft nodes before linearized reading' (duration: 897.161924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:41.040163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:40.142922Z","time spent":"897.235045ms","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-09T03:04:41.043081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.819239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-09T03:04:41.043121Z","caller":"traceutil/trace.go:171","msg":"trace[1289592180] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:511; }","duration":"498.639891ms","start":"2026-02-09T03:04:40.54447Z","end":"2026-02-09T03:04:41.04311Z","steps":["trace[1289592180] 'agreement among raft nodes before linearized reading' (duration: 495.827409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:41.04368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:40.544454Z","time spent":"499.214376ms","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-09T03:04:41.039617Z","caller":"traceutil/trace.go:171","msg":"trace[1967292947] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"453.29785ms","start":"2026-02-09T03:04:40.586311Z","end":"2026-02-09T03:04:41.039609Z","steps":["trace[1967292947] 'process raft request' (duration: 452.303934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:41.045259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:40.58629Z","time spent":"458.926505ms","remote":"127.0.0.1:42104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4364,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:04:41.039779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.278865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-09T03:04:41.046823Z","caller":"traceutil/trace.go:171","msg":"trace[1446981187] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:511; }","duration":"302.354296ms","start":"2026-02-09T03:04:40.744455Z","end":"2026-02-09T03:04:41.046809Z","steps":["trace[1446981187] 'agreement among raft nodes before linearized reading' (duration: 295.299096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:41.046856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:40.744435Z","time spent":"302.409297ms","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-09T03:04:41.047697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.039454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:04:41.047729Z","caller":"traceutil/trace.go:171","msg":"trace[263823081] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:511; }","duration":"703.112396ms","start":"2026-02-09T03:04:40.344606Z","end":"2026-02-09T03:04:41.047719Z","steps":["trace[263823081] 'agreement among raft nodes before linearized reading' (duration: 703.028984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:41.047771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:40.344588Z","time spent":"703.176647ms","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-09T03:04:41.047939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.611902ms","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-09T03:04:41.04796Z","caller":"traceutil/trace.go:171","msg":"trace[480750772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"814.634984ms","start":"2026-02-09T03:04:40.233319Z","end":"2026-02-09T03:04:41.047954Z","steps":["trace[480750772] 'agreement among raft nodes before linearized reading' (duration: 814.557052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:41.047978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:40.233255Z","time spent":"814.719205ms","remote":"127.0.0.1:42198","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-09T03:04:41.039962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.9746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:04:41.052886Z","caller":"traceutil/trace.go:171","msg":"trace[2104635881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"274.909061ms","start":"2026-02-09T03:04:40.777963Z","end":"2026-02-09T03:04:41.052872Z","steps":["trace[2104635881] 'agreement among raft nodes before linearized reading' (duration: 261.983151ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:41.679777Z","caller":"traceutil/trace.go:171","msg":"trace[1117745047] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"125.15182ms","start":"2026-02-09T03:04:41.554609Z","end":"2026-02-09T03:04:41.679761Z","steps":["trace[1117745047] 'process raft request' (duration: 125.043627ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:42.593253Z","caller":"traceutil/trace.go:171","msg":"trace[942442542] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"407.312198ms","start":"2026-02-09T03:04:42.18592Z","end":"2026-02-09T03:04:42.593232Z","steps":["trace[942442542] 'process raft request' (duration: 404.635229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:42.593337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:42.185901Z","time spent":"407.394451ms","remote":"127.0.0.1:41948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T03:04:42.591161Z","caller":"traceutil/trace.go:171","msg":"trace[936635255] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:561; }","duration":"364.361781ms","start":"2026-02-09T03:04:42.226776Z","end":"2026-02-09T03:04:42.591138Z","steps":["trace[936635255] 'read index received' (duration: 364.044842ms)","trace[936635255] 'applied index is now lower than readState.Index' (duration: 315.619µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:04:42.599704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.921571ms","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:1 size:12727"} {"level":"info","ts":"2026-02-09T03:04:42.599864Z","caller":"traceutil/trace.go:171","msg":"trace[1356068027] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:544; }","duration":"373.123027ms","start":"2026-02-09T03:04:42.226731Z","end":"2026-02-09T03:04:42.599854Z","steps":["trace[1356068027] 'agreement among raft nodes before linearized reading' (duration: 372.87655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:42.599914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:42.226717Z","time spent":"373.187888ms","remote":"127.0.0.1:41988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12749,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-02-09T03:04:42.612426Z","caller":"traceutil/trace.go:171","msg":"trace[1534687544] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"245.555654ms","start":"2026-02-09T03:04:42.366857Z","end":"2026-02-09T03:04:42.612412Z","steps":["trace[1534687544] 'process raft request' (duration: 239.080397ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:47.426198Z","caller":"traceutil/trace.go:171","msg":"trace[1324869544] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"181.745442ms","start":"2026-02-09T03:04:47.244437Z","end":"2026-02-09T03:04:47.426183Z","steps":["trace[1324869544] 'read index received' (duration: 181.613297ms)","trace[1324869544] 'applied index is now lower than readState.Index' (duration: 131.525µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:04:47.426391Z","caller":"traceutil/trace.go:171","msg":"trace[1167617819] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"235.510406ms","start":"2026-02-09T03:04:47.190875Z","end":"2026-02-09T03:04:47.426386Z","steps":["trace[1167617819] 'process raft request' (duration: 235.214118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:47.426508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.05788ms","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-09T03:04:47.426524Z","caller":"traceutil/trace.go:171","msg":"trace[1467926685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"182.103022ms","start":"2026-02-09T03:04:47.244416Z","end":"2026-02-09T03:04:47.426519Z","steps":["trace[1467926685] 'agreement among raft nodes before linearized reading' (duration: 182.059171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:47.42659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.282086ms","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-09T03:04:47.426604Z","caller":"traceutil/trace.go:171","msg":"trace[1669458200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:561; }","duration":"179.322577ms","start":"2026-02-09T03:04:47.247276Z","end":"2026-02-09T03:04:47.426599Z","steps":["trace[1669458200] 'agreement among raft nodes before linearized reading' (duration: 179.299826ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:48.079354Z","caller":"traceutil/trace.go:171","msg":"trace[863766257] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"647.643241ms","start":"2026-02-09T03:04:47.431693Z","end":"2026-02-09T03:04:48.079336Z","steps":["trace[863766257] 'process raft request' (duration: 599.276831ms)","trace[863766257] 'compare' (duration: 48.218566ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:04:48.079446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:47.431662Z","time spent":"647.739785ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:04:48.823353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.09546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:04:48.823508Z","caller":"traceutil/trace.go:171","msg":"trace[1781284714] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:580; }","duration":"1.046228096s","start":"2026-02-09T03:04:47.777263Z","end":"2026-02-09T03:04:48.823491Z","steps":["trace[1781284714] 'read index received' (duration: 253.716739ms)","trace[1781284714] 'applied index is now lower than readState.Index' (duration: 792.510607ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:04:48.82367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046395722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:04:48.823724Z","caller":"traceutil/trace.go:171","msg":"trace[819773842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"1.046477263s","start":"2026-02-09T03:04:47.777235Z","end":"2026-02-09T03:04:48.823712Z","steps":["trace[819773842] 'agreement among raft nodes before linearized reading' (duration: 1.04636914s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:48.823748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:47.777204Z","time spent":"1.046539855s","remote":"127.0.0.1:41912","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-09T03:04:48.823756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011778372s","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-09T03:04:48.823792Z","caller":"traceutil/trace.go:171","msg":"trace[1381619875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"1.011840074s","start":"2026-02-09T03:04:47.811939Z","end":"2026-02-09T03:04:48.82378Z","steps":["trace[1381619875] 'agreement among raft nodes before linearized reading' (duration: 1.011778832s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:48.823826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:47.811924Z","time spent":"1.011891745s","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-09T03:04:48.82386Z","caller":"traceutil/trace.go:171","msg":"trace[1569104726] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.39069983s","start":"2026-02-09T03:04:47.433152Z","end":"2026-02-09T03:04:48.823852Z","steps":["trace[1569104726] 'process raft request' (duration: 1.075999854s)","trace[1569104726] 'compare' (duration: 313.985887ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:04:48.823919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:47.433146Z","time spent":"1.390732841s","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:04:48.823969Z","caller":"traceutil/trace.go:171","msg":"trace[1714069969] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"901.317346ms","start":"2026-02-09T03:04:47.922647Z","end":"2026-02-09T03:04:48.823964Z","steps":["trace[1714069969] 'process raft request' (duration: 900.791971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:48.823995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:47.922631Z","time spent":"901.346766ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:04:48.824246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.011545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-09T03:04:48.824276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.6258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.41\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-09T03:04:48.824295Z","caller":"traceutil/trace.go:171","msg":"trace[1684709829] range","detail":"{range_begin:/registry/masterleases/199.204.45.41; range_end:; response_count:1; response_revision:564; }","duration":"748.660611ms","start":"2026-02-09T03:04:48.075629Z","end":"2026-02-09T03:04:48.824289Z","steps":["trace[1684709829] 'agreement among raft nodes before linearized reading' (duration: 748.61815ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:48.824288Z","caller":"traceutil/trace.go:171","msg":"trace[1523177012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"889.077477ms","start":"2026-02-09T03:04:47.9352Z","end":"2026-02-09T03:04:48.824278Z","steps":["trace[1523177012] 'agreement among raft nodes before linearized reading' (duration: 888.941874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:48.824312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:48.0756Z","time spent":"748.709532ms","remote":"127.0.0.1:41928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.41\" "} {"level":"warn","ts":"2026-02-09T03:04:48.824321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:47.935185Z","time spent":"889.129828ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-09T03:04:49.010649Z","caller":"traceutil/trace.go:171","msg":"trace[350457120] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"181.127333ms","start":"2026-02-09T03:04:48.829502Z","end":"2026-02-09T03:04:49.01063Z","steps":["trace[350457120] 'process raft request' (duration: 181.094192ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:49.010906Z","caller":"traceutil/trace.go:171","msg":"trace[431800867] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"181.438021ms","start":"2026-02-09T03:04:48.829461Z","end":"2026-02-09T03:04:49.010899Z","steps":["trace[431800867] 'process raft request' (duration: 181.056171ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:49.01096Z","caller":"traceutil/trace.go:171","msg":"trace[389013600] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"184.489584ms","start":"2026-02-09T03:04:48.826454Z","end":"2026-02-09T03:04:49.010944Z","steps":["trace[389013600] 'read index received' (duration: 139.253298ms)","trace[389013600] 'applied index is now lower than readState.Index' (duration: 45.235166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:04:49.011036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.567077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:04:49.011056Z","caller":"traceutil/trace.go:171","msg":"trace[973407552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"184.611997ms","start":"2026-02-09T03:04:48.826438Z","end":"2026-02-09T03:04:49.01105Z","steps":["trace[973407552] 'agreement among raft nodes before linearized reading' (duration: 184.564266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:51.707702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.276355835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-09T03:04:51.707778Z","caller":"traceutil/trace.go:171","msg":"trace[715119844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"2.276460788s","start":"2026-02-09T03:04:49.431298Z","end":"2026-02-09T03:04:51.707759Z","steps":["trace[715119844] 'range keys from in-memory index tree' (duration: 2.276218051s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:51.707821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:49.431244Z","time spent":"2.276568532s","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T03:04:51.7084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.625857486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4rx2s\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-09T03:04:51.708434Z","caller":"traceutil/trace.go:171","msg":"trace[1882519520] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4rx2s; range_end:; response_count:1; response_revision:570; }","duration":"1.625925788s","start":"2026-02-09T03:04:50.082498Z","end":"2026-02-09T03:04:51.708424Z","steps":["trace[1882519520] 'range keys from in-memory index tree' (duration: 1.625736953s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:51.708461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:50.082479Z","time spent":"1.625976329s","remote":"127.0.0.1:42110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16674,"request content":"key:\"/registry/pods/kube-system/cilium-4rx2s\" "} {"level":"warn","ts":"2026-02-09T03:04:51.708628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624533331s","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-09T03:04:51.708653Z","caller":"traceutil/trace.go:171","msg":"trace[521155061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"1.624586331s","start":"2026-02-09T03:04:50.084059Z","end":"2026-02-09T03:04:51.708645Z","steps":["trace[521155061] 'range keys from in-memory index tree' (duration: 1.624455078s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:51.708677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:50.084044Z","time spent":"1.624627763s","remote":"127.0.0.1:42198","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-09T03:04:51.709093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.522856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:04:51.709122Z","caller":"traceutil/trace.go:171","msg":"trace[1687883296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"929.584308ms","start":"2026-02-09T03:04:50.779527Z","end":"2026-02-09T03:04:51.709112Z","steps":["trace[1687883296] 'range keys from in-memory index tree' (duration: 929.397783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:51.709153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:50.779486Z","time spent":"929.66034ms","remote":"127.0.0.1:41912","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-09T03:04:51.709289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.780269ms","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-09T03:04:51.709314Z","caller":"traceutil/trace.go:171","msg":"trace[1059577123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:570; }","duration":"876.82684ms","start":"2026-02-09T03:04:50.83248Z","end":"2026-02-09T03:04:51.709306Z","steps":["trace[1059577123] 'range keys from in-memory index tree' (duration: 876.700347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:51.709338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:50.832449Z","time spent":"876.883372ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T03:04:51.710169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.333156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:04:51.71021Z","caller":"traceutil/trace.go:171","msg":"trace[1149752149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:570; }","duration":"695.402338ms","start":"2026-02-09T03:04:51.014798Z","end":"2026-02-09T03:04:51.710201Z","steps":["trace[1149752149] 'range keys from in-memory index tree' (duration: 695.249443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:51.710241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:51.014785Z","time spent":"695.447519ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T03:04:51.710604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.969936ms","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-09T03:04:51.710688Z","caller":"traceutil/trace.go:171","msg":"trace[254366104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"695.063658ms","start":"2026-02-09T03:04:51.015614Z","end":"2026-02-09T03:04:51.710678Z","steps":["trace[254366104] 'range keys from in-memory index tree' (duration: 694.896823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:51.710848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:51.015588Z","time spent":"695.12277ms","remote":"127.0.0.1:42198","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-09T03:04:52.19985Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.076918784s","expected-duration":"1s"} {"level":"info","ts":"2026-02-09T03:04:52.200237Z","caller":"traceutil/trace.go:171","msg":"trace[178018201] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.077352726s","start":"2026-02-09T03:04:51.122853Z","end":"2026-02-09T03:04:52.200206Z","steps":["trace[178018201] 'process raft request' (duration: 1.077210162s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:52.200374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:51.122834Z","time spent":"1.07746734s","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:04:52.200959Z","caller":"traceutil/trace.go:171","msg":"trace[546475851] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:591; }","duration":"481.200863ms","start":"2026-02-09T03:04:51.719744Z","end":"2026-02-09T03:04:52.200945Z","steps":["trace[546475851] 'read index received' (duration: 481.195893ms)","trace[546475851] 'applied index is now lower than readState.Index' (duration: 3.99µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:04:52.201029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.275435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:04:52.201063Z","caller":"traceutil/trace.go:171","msg":"trace[1507097367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"481.325826ms","start":"2026-02-09T03:04:51.719726Z","end":"2026-02-09T03:04:52.201052Z","steps":["trace[1507097367] 'agreement among raft nodes before linearized reading' (duration: 481.269385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:52.201093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:51.719715Z","time spent":"481.371177ms","remote":"127.0.0.1:41914","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-09T03:04:52.411936Z","caller":"traceutil/trace.go:171","msg":"trace[110263277] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"688.118693ms","start":"2026-02-09T03:04:51.723799Z","end":"2026-02-09T03:04:52.411918Z","steps":["trace[110263277] 'process raft request' (duration: 688.050771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:52.412047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.692362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:04:52.412075Z","caller":"traceutil/trace.go:171","msg":"trace[2026415402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"202.748984ms","start":"2026-02-09T03:04:52.209317Z","end":"2026-02-09T03:04:52.412066Z","steps":["trace[2026415402] 'agreement among raft nodes before linearized reading' (duration: 202.680152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:52.412074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:51.723792Z","time spent":"688.220316ms","remote":"127.0.0.1:42110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:04:52.41226Z","caller":"traceutil/trace.go:171","msg":"trace[530974798] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"685.364387ms","start":"2026-02-09T03:04:51.726888Z","end":"2026-02-09T03:04:52.412252Z","steps":["trace[530974798] 'process raft request' (duration: 684.991577ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:52.412278Z","caller":"traceutil/trace.go:171","msg":"trace[1301158293] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"692.477504ms","start":"2026-02-09T03:04:51.71979Z","end":"2026-02-09T03:04:52.412267Z","steps":["trace[1301158293] 'process raft request' (duration: 691.845016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:52.412316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:51.719783Z","time spent":"692.512585ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:04:52.412323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:51.726871Z","time spent":"685.409348ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:04:52.412435Z","caller":"traceutil/trace.go:171","msg":"trace[1097096236] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"692.593167ms","start":"2026-02-09T03:04:51.719836Z","end":"2026-02-09T03:04:52.412429Z","steps":["trace[1097096236] 'process raft request' (duration: 691.920989ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:04:52.411962Z","caller":"traceutil/trace.go:171","msg":"trace[1594183511] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:591; }","duration":"202.60666ms","start":"2026-02-09T03:04:52.209338Z","end":"2026-02-09T03:04:52.411945Z","steps":["trace[1594183511] 'read index received' (duration: 202.24453ms)","trace[1594183511] 'applied index is now lower than readState.Index' (duration: 361.28µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:04:52.412477Z","caller":"traceutil/trace.go:171","msg":"trace[1102600164] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"692.610397ms","start":"2026-02-09T03:04:51.719859Z","end":"2026-02-09T03:04:52.412469Z","steps":["trace[1102600164] 'process raft request' (duration: 691.934629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:52.412474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:51.719833Z","time spent":"692.622778ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:04:52.412521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:51.719855Z","time spent":"692.635529ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:04:52.412622Z","caller":"traceutil/trace.go:171","msg":"trace[496236247] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"689.369869ms","start":"2026-02-09T03:04:51.723246Z","end":"2026-02-09T03:04:52.412616Z","steps":["trace[496236247] 'process raft request' (duration: 688.572086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:52.412655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:51.72324Z","time spent":"689.3945ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:04:57.682566Z","caller":"traceutil/trace.go:171","msg":"trace[1503604208] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"335.643778ms","start":"2026-02-09T03:04:57.346904Z","end":"2026-02-09T03:04:57.682548Z","steps":["trace[1503604208] 'process raft request' (duration: 335.525994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:04:57.682672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:04:57.346877Z","time spent":"335.748621ms","remote":"127.0.0.1:41978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T03:05:03.35131Z","caller":"traceutil/trace.go:171","msg":"trace[1835438877] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"257.88092ms","start":"2026-02-09T03:05:03.093413Z","end":"2026-02-09T03:05:03.351294Z","steps":["trace[1835438877] 'process raft request' (duration: 257.746586ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:03.486607Z","caller":"traceutil/trace.go:171","msg":"trace[410576700] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"118.134564ms","start":"2026-02-09T03:05:03.368455Z","end":"2026-02-09T03:05:03.48659Z","steps":["trace[410576700] 'process raft request' (duration: 115.518526ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:03.489238Z","caller":"traceutil/trace.go:171","msg":"trace[1229766218] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"106.285621ms","start":"2026-02-09T03:05:03.382939Z","end":"2026-02-09T03:05:03.489225Z","steps":["trace[1229766218] 'process raft request' (duration: 106.213198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:05:04.374891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.692882ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-09T03:05:04.375118Z","caller":"traceutil/trace.go:171","msg":"trace[1836636655] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"279.694562ms","start":"2026-02-09T03:05:04.095411Z","end":"2026-02-09T03:05:04.375105Z","steps":["trace[1836636655] 'process raft request' (duration: 279.576058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:05:04.499427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.290296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:05:04.499486Z","caller":"traceutil/trace.go:171","msg":"trace[1184066678] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"285.155376ms","start":"2026-02-09T03:05:04.21432Z","end":"2026-02-09T03:05:04.499475Z","steps":["trace[1184066678] 'process raft request' (duration: 160.775638ms)","trace[1184066678] 'compare' (duration: 124.017498ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:05:04.62927Z","caller":"traceutil/trace.go:171","msg":"trace[1763448458] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"110.471772ms","start":"2026-02-09T03:05:04.51878Z","end":"2026-02-09T03:05:04.629252Z","steps":["trace[1763448458] 'process raft request' (duration: 110.004078ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:04.70975Z","caller":"traceutil/trace.go:171","msg":"trace[177758379] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:682; }","duration":"167.623212ms","start":"2026-02-09T03:05:04.542091Z","end":"2026-02-09T03:05:04.709715Z","steps":["trace[177758379] 'read index received' (duration: 86.97966ms)","trace[177758379] 'applied index is now lower than readState.Index' (duration: 80.643062ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:05:04.710015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.04298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-09T03:05:04.710035Z","caller":"traceutil/trace.go:171","msg":"trace[1509979981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:661; }","duration":"100.103602ms","start":"2026-02-09T03:05:04.609925Z","end":"2026-02-09T03:05:04.710029Z","steps":["trace[1509979981] 'agreement among raft nodes before linearized reading' (duration: 99.998689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:05:04.710048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.933301ms","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-09T03:05:04.710101Z","caller":"traceutil/trace.go:171","msg":"trace[629715700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"168.021734ms","start":"2026-02-09T03:05:04.542066Z","end":"2026-02-09T03:05:04.710088Z","steps":["trace[629715700] 'agreement among raft nodes before linearized reading' (duration: 167.794137ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:04.710322Z","caller":"traceutil/trace.go:171","msg":"trace[1883770089] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"169.150517ms","start":"2026-02-09T03:05:04.541166Z","end":"2026-02-09T03:05:04.710316Z","steps":["trace[1883770089] 'process raft request' (duration: 168.528988ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:04.710391Z","caller":"traceutil/trace.go:171","msg":"trace[1496866276] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"169.363403ms","start":"2026-02-09T03:05:04.541023Z","end":"2026-02-09T03:05:04.710386Z","steps":["trace[1496866276] 'process raft request' (duration: 168.521068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:05:04.849514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.290877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:05:04.849717Z","caller":"traceutil/trace.go:171","msg":"trace[1607325143] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"131.064669ms","start":"2026-02-09T03:05:04.71864Z","end":"2026-02-09T03:05:04.849705Z","steps":["trace[1607325143] 'process raft request' (duration: 131.027428ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:04.849781Z","caller":"traceutil/trace.go:171","msg":"trace[534003168] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"131.221974ms","start":"2026-02-09T03:05:04.718538Z","end":"2026-02-09T03:05:04.84976Z","steps":["trace[534003168] 'process raft request' (duration: 20.579846ms)","trace[534003168] 'compare' (duration: 110.173254ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:05:04.849896Z","caller":"traceutil/trace.go:171","msg":"trace[866664035] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"131.274926ms","start":"2026-02-09T03:05:04.718615Z","end":"2026-02-09T03:05:04.84989Z","steps":["trace[866664035] 'process raft request' (duration: 131.018668ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:04.853964Z","caller":"traceutil/trace.go:171","msg":"trace[1535410348] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"129.213324ms","start":"2026-02-09T03:05:04.724736Z","end":"2026-02-09T03:05:04.853949Z","steps":["trace[1535410348] 'process raft request' (duration: 128.694087ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:27.20463Z","caller":"traceutil/trace.go:171","msg":"trace[515506326] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"201.963185ms","start":"2026-02-09T03:05:27.002611Z","end":"2026-02-09T03:05:27.204575Z","steps":["trace[515506326] 'process raft request' (duration: 201.871473ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:27.219418Z","caller":"traceutil/trace.go:171","msg":"trace[1557627973] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"215.172279ms","start":"2026-02-09T03:05:27.004229Z","end":"2026-02-09T03:05:27.219401Z","steps":["trace[1557627973] 'process raft request' (duration: 215.049295ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:27.219581Z","caller":"traceutil/trace.go:171","msg":"trace[328406847] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"213.722272ms","start":"2026-02-09T03:05:27.005854Z","end":"2026-02-09T03:05:27.219576Z","steps":["trace[328406847] 'process raft request' (duration: 213.526145ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:27.219669Z","caller":"traceutil/trace.go:171","msg":"trace[1565076931] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"211.740867ms","start":"2026-02-09T03:05:27.007923Z","end":"2026-02-09T03:05:27.219664Z","steps":["trace[1565076931] 'process raft request' (duration: 211.634733ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:28.241638Z","caller":"traceutil/trace.go:171","msg":"trace[547216471] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"102.745661ms","start":"2026-02-09T03:05:28.138873Z","end":"2026-02-09T03:05:28.241618Z","steps":["trace[547216471] 'process raft request' (duration: 61.219971ms)","trace[547216471] 'compare' (duration: 41.418107ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:05:29.426161Z","caller":"traceutil/trace.go:171","msg":"trace[1563296690] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"200.116788ms","start":"2026-02-09T03:05:29.226022Z","end":"2026-02-09T03:05:29.426139Z","steps":["trace[1563296690] 'process raft request' (duration: 107.571793ms)","trace[1563296690] 'compare' (duration: 92.297426ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:05:29.426163Z","caller":"traceutil/trace.go:171","msg":"trace[246602275] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"198.532696ms","start":"2026-02-09T03:05:29.22758Z","end":"2026-02-09T03:05:29.426113Z","steps":["trace[246602275] 'process raft request' (duration: 198.484194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:05:29.426562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.620981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-09T03:05:29.426587Z","caller":"traceutil/trace.go:171","msg":"trace[1474312630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"199.670102ms","start":"2026-02-09T03:05:29.226912Z","end":"2026-02-09T03:05:29.426582Z","steps":["trace[1474312630] 'agreement among raft nodes before linearized reading' (duration: 199.584509ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:29.426484Z","caller":"traceutil/trace.go:171","msg":"trace[414824650] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"199.548919ms","start":"2026-02-09T03:05:29.226929Z","end":"2026-02-09T03:05:29.426478Z","steps":["trace[414824650] 'read index received' (duration: 106.677823ms)","trace[414824650] 'applied index is now lower than readState.Index' (duration: 92.870446ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:05:37.739722Z","caller":"traceutil/trace.go:171","msg":"trace[1755994488] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"124.975707ms","start":"2026-02-09T03:05:37.614724Z","end":"2026-02-09T03:05:37.7397Z","steps":["trace[1755994488] 'process raft request' (duration: 124.773241ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:05:37.797182Z","caller":"traceutil/trace.go:171","msg":"trace[1105616897] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"179.711284ms","start":"2026-02-09T03:05:37.617447Z","end":"2026-02-09T03:05:37.797158Z","steps":["trace[1105616897] 'process raft request' (duration: 179.574839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:05:43.738763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.70849ms","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-09T03:05:43.738825Z","caller":"traceutil/trace.go:171","msg":"trace[1329084683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:828; }","duration":"125.810932ms","start":"2026-02-09T03:05:43.612998Z","end":"2026-02-09T03:05:43.738809Z","steps":["trace[1329084683] 'range keys from in-memory index tree' (duration: 125.573815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:06:13.652142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.355676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-09T03:06:13.652425Z","caller":"traceutil/trace.go:171","msg":"trace[1108839264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:942; }","duration":"487.690228ms","start":"2026-02-09T03:06:13.164717Z","end":"2026-02-09T03:06:13.652407Z","steps":["trace[1108839264] 'agreement among raft nodes before linearized reading' (duration: 487.213041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:06:13.652522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:06:13.164701Z","time spent":"487.804782ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-09T03:06:13.652856Z","caller":"traceutil/trace.go:171","msg":"trace[732671062] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"487.153138ms","start":"2026-02-09T03:06:13.164749Z","end":"2026-02-09T03:06:13.651902Z","steps":["trace[732671062] 'read index received' (duration: 448.197357ms)","trace[732671062] 'applied index is now lower than readState.Index' (duration: 38.954281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:06:13.653118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.536398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:06:13.653207Z","caller":"traceutil/trace.go:171","msg":"trace[1790065778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:942; }","duration":"161.713676ms","start":"2026-02-09T03:06:13.491482Z","end":"2026-02-09T03:06:13.653195Z","steps":["trace[1790065778] 'agreement among raft nodes before linearized reading' (duration: 161.541909ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:06:21.823593Z","caller":"traceutil/trace.go:171","msg":"trace[1658024920] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"107.727929ms","start":"2026-02-09T03:06:21.715848Z","end":"2026-02-09T03:06:21.823576Z","steps":["trace[1658024920] 'read index received' (duration: 107.539362ms)","trace[1658024920] 'applied index is now lower than readState.Index' (duration: 187.537µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:06:21.823881Z","caller":"traceutil/trace.go:171","msg":"trace[1987858475] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"115.663151ms","start":"2026-02-09T03:06:21.708209Z","end":"2026-02-09T03:06:21.823872Z","steps":["trace[1987858475] 'process raft request' (duration: 115.234115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:06:21.824141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.276919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:06:21.824172Z","caller":"traceutil/trace.go:171","msg":"trace[984922758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"108.342752ms","start":"2026-02-09T03:06:21.71582Z","end":"2026-02-09T03:06:21.824163Z","steps":["trace[984922758] 'agreement among raft nodes before linearized reading' (duration: 108.227107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:06:22.419625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.35765ms","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-09T03:06:22.41969Z","caller":"traceutil/trace.go:171","msg":"trace[2130120472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"362.465934ms","start":"2026-02-09T03:06:22.057209Z","end":"2026-02-09T03:06:22.419675Z","steps":["trace[2130120472] 'range keys from in-memory index tree' (duration: 362.06416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:06:22.419722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:06:22.05719Z","time spent":"362.525616ms","remote":"127.0.0.1:42198","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-09T03:06:22.419766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.039667ms","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-09T03:06:22.419824Z","caller":"traceutil/trace.go:171","msg":"trace[986236010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"359.12252ms","start":"2026-02-09T03:06:22.060687Z","end":"2026-02-09T03:06:22.419809Z","steps":["trace[986236010] 'range keys from in-memory index tree' (duration: 358.914583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:06:22.419854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:06:22.06068Z","time spent":"359.167371ms","remote":"127.0.0.1:42198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T03:06:22.420064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.457978ms","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-09T03:06:22.420088Z","caller":"traceutil/trace.go:171","msg":"trace[1914293007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"456.5097ms","start":"2026-02-09T03:06:21.963571Z","end":"2026-02-09T03:06:22.420081Z","steps":["trace[1914293007] 'range keys from in-memory index tree' (duration: 456.329993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:06:22.420106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:06:21.963555Z","time spent":"456.546232ms","remote":"127.0.0.1:42198","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-09T03:06:22.420188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.398838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-09T03:06:22.4202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.164756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:06:22.420218Z","caller":"traceutil/trace.go:171","msg":"trace[714451495] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:973; }","duration":"121.456581ms","start":"2026-02-09T03:06:22.298752Z","end":"2026-02-09T03:06:22.420208Z","steps":["trace[714451495] 'range keys from in-memory index tree' (duration: 121.336976ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:06:22.420223Z","caller":"traceutil/trace.go:171","msg":"trace[1258816624] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:973; }","duration":"444.213317ms","start":"2026-02-09T03:06:21.976004Z","end":"2026-02-09T03:06:22.420217Z","steps":["trace[1258816624] 'count revisions from in-memory index tree' (duration: 444.117024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:06:22.420248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:06:21.975992Z","time spent":"444.248139ms","remote":"127.0.0.1:42290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "}