{"level":"warn","ts":"2026-03-04T01:22:58.756906Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T01:22:58.757084Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.149: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.149:2380","--initial-cluster=instance=https://199.204.45.149:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.149:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.149: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-03-04T01:22:58.757223Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T01:22:58.757246Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-03-04T01:22:58.757287Z","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-03-04T01:22:58.758612Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"]} {"level":"info","ts":"2026-03-04T01:22:58.75891Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.149: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-03-04T01:22:58.763591Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.268876ms"} {"level":"info","ts":"2026-03-04T01:22:58.775706Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1b675f124322401","cluster-id":"913dfbf24a38e0aa"} {"level":"info","ts":"2026-03-04T01:22:58.775775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=()"} {"level":"info","ts":"2026-03-04T01:22:58.775796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 0"} {"level":"info","ts":"2026-03-04T01:22:58.775806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1b675f124322401 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-04T01:22:58.775817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 1"} {"level":"info","ts":"2026-03-04T01:22:58.775857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"warn","ts":"2026-03-04T01:22:58.795381Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-04T01:22:58.797054Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-04T01:22:58.798386Z","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-03-04T01:22:58.801298Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1b675f124322401","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-04T01:22:58.801851Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1b675f124322401","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-04T01:22:58.801968Z","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-03-04T01:22:58.802026Z","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-03-04T01:22:58.802032Z","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-03-04T01:22:58.814142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"info","ts":"2026-03-04T01:22:58.8143Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","added-peer-id":"b1b675f124322401","added-peer-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-03-04T01:22:58.815377Z","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-03-04T01:22:58.815652Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1b675f124322401","initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-04T01:22:58.815687Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-04T01:22:58.815722Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-03-04T01:22:58.815818Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-03-04T01:22:59.6824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 is starting a new election at term 1"} {"level":"info","ts":"2026-03-04T01:22:59.682489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-04T01:22:59.682516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgPreVoteResp from b1b675f124322401 at term 1"} {"level":"info","ts":"2026-03-04T01:22:59.682539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became candidate at term 2"} {"level":"info","ts":"2026-03-04T01:22:59.682551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgVoteResp from b1b675f124322401 at term 2"} {"level":"info","ts":"2026-03-04T01:22:59.682567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became leader at term 2"} {"level":"info","ts":"2026-03-04T01:22:59.682587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1b675f124322401 elected leader b1b675f124322401 at term 2"} {"level":"info","ts":"2026-03-04T01:22:59.684161Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T01:22:59.685918Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1b675f124322401","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.149:2379]}","request-path":"/0/members/b1b675f124322401/attributes","cluster-id":"913dfbf24a38e0aa","publish-timeout":"7s"} {"level":"info","ts":"2026-03-04T01:22:59.685977Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T01:22:59.686072Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T01:22:59.686213Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-04T01:22:59.686236Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-04T01:22:59.686255Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T01:22:59.686374Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T01:22:59.686413Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T01:22:59.689245Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-04T01:22:59.689765Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.149:2379"} {"level":"info","ts":"2026-03-04T01:23:10.530696Z","caller":"traceutil/trace.go:171","msg":"trace[1989768711] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"139.465315ms","start":"2026-03-04T01:23:10.391203Z","end":"2026-03-04T01:23:10.530668Z","steps":["trace[1989768711] 'read index received' (duration: 109.761882ms)","trace[1989768711] 'applied index is now lower than readState.Index' (duration: 29.701763ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:23:10.530885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.671329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-mblg6\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-03-04T01:23:10.530928Z","caller":"traceutil/trace.go:171","msg":"trace[1421212318] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-mblg6; range_end:; response_count:1; response_revision:350; }","duration":"139.792712ms","start":"2026-03-04T01:23:10.391128Z","end":"2026-03-04T01:23:10.53092Z","steps":["trace[1421212318] 'agreement among raft nodes before linearized reading' (duration: 139.67775ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:10.648397Z","caller":"traceutil/trace.go:171","msg":"trace[891017875] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"116.322011ms","start":"2026-03-04T01:23:10.532026Z","end":"2026-03-04T01:23:10.648348Z","steps":["trace[891017875] 'process raft request' (duration: 114.184532ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:10.717708Z","caller":"traceutil/trace.go:171","msg":"trace[1184599769] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:362; }","duration":"122.127042ms","start":"2026-03-04T01:23:10.595551Z","end":"2026-03-04T01:23:10.717679Z","steps":["trace[1184599769] 'read index received' (duration: 50.638835ms)","trace[1184599769] 'applied index is now lower than readState.Index' (duration: 71.487147ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:23:10.717824Z","caller":"traceutil/trace.go:171","msg":"trace[713561472] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"177.814962ms","start":"2026-03-04T01:23:10.539977Z","end":"2026-03-04T01:23:10.717792Z","steps":["trace[713561472] 'process raft request' (duration: 177.536136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:10.717894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.314207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T01:23:10.717929Z","caller":"traceutil/trace.go:171","msg":"trace[592611582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:352; }","duration":"122.43262ms","start":"2026-03-04T01:23:10.595486Z","end":"2026-03-04T01:23:10.717919Z","steps":["trace[592611582] 'agreement among raft nodes before linearized reading' (duration: 122.307937ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:31.478842Z","caller":"traceutil/trace.go:171","msg":"trace[1317795072] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"479.183238ms","start":"2026-03-04T01:23:30.999635Z","end":"2026-03-04T01:23:31.478818Z","steps":["trace[1317795072] 'read index received' (duration: 478.948572ms)","trace[1317795072] 'applied index is now lower than readState.Index' (duration: 233.616µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:23:31.47902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.357731ms","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-03-04T01:23:31.478986Z","caller":"traceutil/trace.go:171","msg":"trace[587753292] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"574.343379ms","start":"2026-03-04T01:23:30.904604Z","end":"2026-03-04T01:23:31.478947Z","steps":["trace[587753292] 'process raft request' (duration: 574.031743ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:31.479067Z","caller":"traceutil/trace.go:171","msg":"trace[1903549486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"479.455254ms","start":"2026-03-04T01:23:30.9996Z","end":"2026-03-04T01:23:31.479056Z","steps":["trace[1903549486] 'agreement among raft nodes before linearized reading' (duration: 479.354062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:31.47914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:30.999585Z","time spent":"479.546426ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T01:23:31.479204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.785606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-04T01:23:31.47924Z","caller":"traceutil/trace.go:171","msg":"trace[1775633737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:473; }","duration":"278.863488ms","start":"2026-03-04T01:23:31.200368Z","end":"2026-03-04T01:23:31.479231Z","steps":["trace[1775633737] 'agreement among raft nodes before linearized reading' (duration: 278.776446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:31.479898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:30.904588Z","time spent":"574.495804ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:23:35.32744Z","caller":"traceutil/trace.go:171","msg":"trace[1169762694] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"133.154531ms","start":"2026-03-04T01:23:35.194263Z","end":"2026-03-04T01:23:35.327417Z","steps":["trace[1169762694] 'process raft request' (duration: 133.025538ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:37.6573Z","caller":"traceutil/trace.go:171","msg":"trace[1164348148] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"135.619427ms","start":"2026-03-04T01:23:37.521645Z","end":"2026-03-04T01:23:37.657265Z","steps":["trace[1164348148] 'process raft request' (duration: 135.569716ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:37.657818Z","caller":"traceutil/trace.go:171","msg":"trace[935075790] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"138.030991ms","start":"2026-03-04T01:23:37.519775Z","end":"2026-03-04T01:23:37.657806Z","steps":["trace[935075790] 'process raft request' (duration: 137.277764ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:40.026489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594527167734375017,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T01:23:40.527728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594527167734375017,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T01:23:40.699402Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.175445546s","expected-duration":"1s"} {"level":"info","ts":"2026-03-04T01:23:40.700212Z","caller":"traceutil/trace.go:171","msg":"trace[858265188] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"1.174418061s","start":"2026-03-04T01:23:39.525539Z","end":"2026-03-04T01:23:40.699957Z","steps":["trace[858265188] 'read index received' (duration: 1.174116505s)","trace[858265188] 'applied index is now lower than readState.Index' (duration: 299.566µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:23:40.700294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174743919s","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-03-04T01:23:40.700374Z","caller":"traceutil/trace.go:171","msg":"trace[1715659995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:497; }","duration":"1.174952463s","start":"2026-03-04T01:23:39.525389Z","end":"2026-03-04T01:23:40.700342Z","steps":["trace[1715659995] 'agreement among raft nodes before linearized reading' (duration: 1.174749788s)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:40.70039Z","caller":"traceutil/trace.go:171","msg":"trace[161605295] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.176205762s","start":"2026-03-04T01:23:39.523832Z","end":"2026-03-04T01:23:40.700038Z","steps":["trace[161605295] 'process raft request' (duration: 1.175911576s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:40.700444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:39.525364Z","time spent":"1.175068426s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-04T01:23:40.700579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0377205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-04T01:23:40.700596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:39.523814Z","time spent":"1.176680722s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:23:40.70063Z","caller":"traceutil/trace.go:171","msg":"trace[1891334254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:497; }","duration":"1.037794083s","start":"2026-03-04T01:23:39.662818Z","end":"2026-03-04T01:23:40.700612Z","steps":["trace[1891334254] 'agreement among raft nodes before linearized reading' (duration: 1.037618158s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:40.700675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:39.66281Z","time spent":"1.037855404s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T01:23:40.700949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037859253s","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-03-04T01:23:40.701305Z","caller":"traceutil/trace.go:171","msg":"trace[122615349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:497; }","duration":"1.038811925s","start":"2026-03-04T01:23:39.662475Z","end":"2026-03-04T01:23:40.701287Z","steps":["trace[122615349] 'agreement among raft nodes before linearized reading' (duration: 1.037881593s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:23:40.701357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:23:39.662444Z","time spent":"1.038902007s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-04T01:23:40.86396Z","caller":"traceutil/trace.go:171","msg":"trace[1597494835] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"155.864015ms","start":"2026-03-04T01:23:40.708069Z","end":"2026-03-04T01:23:40.863933Z","steps":["trace[1597494835] 'process raft request' (duration: 155.821404ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:40.86407Z","caller":"traceutil/trace.go:171","msg":"trace[1449412584] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"157.074063ms","start":"2026-03-04T01:23:40.706975Z","end":"2026-03-04T01:23:40.864049Z","steps":["trace[1449412584] 'process raft request' (duration: 150.703889ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:23:40.864221Z","caller":"traceutil/trace.go:171","msg":"trace[1873341957] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"156.534451ms","start":"2026-03-04T01:23:40.707673Z","end":"2026-03-04T01:23:40.864208Z","steps":["trace[1873341957] 'process raft request' (duration: 156.168292ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:00.556324Z","caller":"traceutil/trace.go:171","msg":"trace[825409604] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"263.529812ms","start":"2026-03-04T01:24:00.292752Z","end":"2026-03-04T01:24:00.556282Z","steps":["trace[825409604] 'process raft request' (duration: 263.309427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:01.704313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.005672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:24:01.704444Z","caller":"traceutil/trace.go:171","msg":"trace[2077710946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"569.38855ms","start":"2026-03-04T01:24:01.135026Z","end":"2026-03-04T01:24:01.704414Z","steps":["trace[2077710946] 'range keys from in-memory index tree' (duration: 568.840678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:01.704489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:01.134957Z","time spent":"569.522264ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-04T01:24:13.315492Z","caller":"traceutil/trace.go:171","msg":"trace[1054118401] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"126.54556ms","start":"2026-03-04T01:24:13.188904Z","end":"2026-03-04T01:24:13.31545Z","steps":["trace[1054118401] 'process raft request' (duration: 122.395353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:22.758392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.683895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-04T01:24:22.758519Z","caller":"traceutil/trace.go:171","msg":"trace[335320838] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:752; }","duration":"110.92996ms","start":"2026-03-04T01:24:22.647563Z","end":"2026-03-04T01:24:22.758493Z","steps":["trace[335320838] 'range keys from in-memory index tree' (duration: 110.491881ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:26.343117Z","caller":"traceutil/trace.go:171","msg":"trace[1991884302] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"173.791541ms","start":"2026-03-04T01:24:26.169285Z","end":"2026-03-04T01:24:26.343076Z","steps":["trace[1991884302] 'process raft request' (duration: 173.608247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:26.981819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.210652ms","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-03-04T01:24:26.981923Z","caller":"traceutil/trace.go:171","msg":"trace[1039255108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:764; }","duration":"181.380305ms","start":"2026-03-04T01:24:26.800519Z","end":"2026-03-04T01:24:26.981899Z","steps":["trace[1039255108] 'range keys from in-memory index tree' (duration: 181.009327ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:27.098782Z","caller":"traceutil/trace.go:171","msg":"trace[267278186] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"111.742219ms","start":"2026-03-04T01:24:26.987013Z","end":"2026-03-04T01:24:27.098755Z","steps":["trace[267278186] 'process raft request' (duration: 111.590485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:27.653651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.170451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-04T01:24:27.653775Z","caller":"traceutil/trace.go:171","msg":"trace[915530723] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:765; }","duration":"402.357115ms","start":"2026-03-04T01:24:27.251388Z","end":"2026-03-04T01:24:27.653745Z","steps":["trace[915530723] 'count revisions from in-memory index tree' (duration: 402.028758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:27.653817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.805747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-04T01:24:27.653885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:27.251369Z","time spent":"402.498839ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-03-04T01:24:27.653943Z","caller":"traceutil/trace.go:171","msg":"trace[1564634312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"178.978901ms","start":"2026-03-04T01:24:27.47493Z","end":"2026-03-04T01:24:27.653909Z","steps":["trace[1564634312] 'range keys from in-memory index tree' (duration: 178.578591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:27.653892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.581831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-04T01:24:27.653761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.48962ms","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-03-04T01:24:27.654183Z","caller":"traceutil/trace.go:171","msg":"trace[216720715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"305.94407ms","start":"2026-03-04T01:24:27.348201Z","end":"2026-03-04T01:24:27.654145Z","steps":["trace[216720715] 'range keys from in-memory index tree' (duration: 305.261125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:27.654356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:27.348188Z","time spent":"306.042043ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-04T01:24:27.654198Z","caller":"traceutil/trace.go:171","msg":"trace[1968805316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"296.01713ms","start":"2026-03-04T01:24:27.358103Z","end":"2026-03-04T01:24:27.65412Z","steps":["trace[1968805316] 'range keys from in-memory index tree' (duration: 295.343265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:27.655351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.104732ms","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-03-04T01:24:27.655487Z","caller":"traceutil/trace.go:171","msg":"trace[239735502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"315.330708ms","start":"2026-03-04T01:24:27.340124Z","end":"2026-03-04T01:24:27.655455Z","steps":["trace[239735502] 'range keys from in-memory index tree' (duration: 313.219768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:27.655549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:27.340076Z","time spent":"315.460271ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-04T01:24:27.786731Z","caller":"traceutil/trace.go:171","msg":"trace[930784629] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"126.617682ms","start":"2026-03-04T01:24:27.660086Z","end":"2026-03-04T01:24:27.786704Z","steps":["trace[930784629] 'process raft request' (duration: 126.426398ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:27.797378Z","caller":"traceutil/trace.go:171","msg":"trace[1497878238] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"137.203086ms","start":"2026-03-04T01:24:27.660143Z","end":"2026-03-04T01:24:27.797346Z","steps":["trace[1497878238] 'process raft request' (duration: 136.953189ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:27.797401Z","caller":"traceutil/trace.go:171","msg":"trace[1707968299] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"135.281693ms","start":"2026-03-04T01:24:27.662094Z","end":"2026-03-04T01:24:27.797375Z","steps":["trace[1707968299] 'process raft request' (duration: 135.16732ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:27.797368Z","caller":"traceutil/trace.go:171","msg":"trace[1859642249] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"135.211841ms","start":"2026-03-04T01:24:27.662117Z","end":"2026-03-04T01:24:27.797329Z","steps":["trace[1859642249] 'process raft request' (duration: 135.17337ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:28.071326Z","caller":"traceutil/trace.go:171","msg":"trace[1405254944] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"120.835978ms","start":"2026-03-04T01:24:27.950453Z","end":"2026-03-04T01:24:28.071289Z","steps":["trace[1405254944] 'read index received' (duration: 53.526455ms)","trace[1405254944] 'applied index is now lower than readState.Index' (duration: 67.306953ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:24:28.07154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.059174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-04T01:24:28.071648Z","caller":"traceutil/trace.go:171","msg":"trace[1706588] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:769; }","duration":"121.262568ms","start":"2026-03-04T01:24:27.950362Z","end":"2026-03-04T01:24:28.071624Z","steps":["trace[1706588] 'agreement among raft nodes before linearized reading' (duration: 121.039843ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:29.222322Z","caller":"traceutil/trace.go:171","msg":"trace[2061475710] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"113.061178ms","start":"2026-03-04T01:24:29.109226Z","end":"2026-03-04T01:24:29.222287Z","steps":["trace[2061475710] 'process raft request' (duration: 112.767352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:30.303393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594527167734375631,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-04T01:24:30.665257Z","caller":"traceutil/trace.go:171","msg":"trace[1767610132] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"862.577907ms","start":"2026-03-04T01:24:29.802646Z","end":"2026-03-04T01:24:30.665224Z","steps":["trace[1767610132] 'read index received' (duration: 862.355882ms)","trace[1767610132] 'applied index is now lower than readState.Index' (duration: 220.065µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:24:30.665395Z","caller":"traceutil/trace.go:171","msg":"trace[366346893] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"868.914613ms","start":"2026-03-04T01:24:29.796433Z","end":"2026-03-04T01:24:30.665348Z","steps":["trace[366346893] 'process raft request' (duration: 868.571635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:30.665646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:29.7964Z","time spent":"869.085117ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:24:30.665659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.043694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-04T01:24:30.665704Z","caller":"traceutil/trace.go:171","msg":"trace[1497216199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:771; }","duration":"862.143716ms","start":"2026-03-04T01:24:29.803546Z","end":"2026-03-04T01:24:30.66569Z","steps":["trace[1497216199] 'agreement among raft nodes before linearized reading' (duration: 861.943402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:30.665746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:29.803529Z","time spent":"862.207788ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T01:24:30.66596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.278894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T01:24:30.665996Z","caller":"traceutil/trace.go:171","msg":"trace[1649040491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:771; }","duration":"863.367356ms","start":"2026-03-04T01:24:29.802619Z","end":"2026-03-04T01:24:30.665986Z","steps":["trace[1649040491] 'agreement among raft nodes before linearized reading' (duration: 862.847443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:30.666059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:29.802588Z","time spent":"863.436827ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-04T01:24:30.666154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.041489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:24:30.666269Z","caller":"traceutil/trace.go:171","msg":"trace[649732192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"532.270394ms","start":"2026-03-04T01:24:30.133973Z","end":"2026-03-04T01:24:30.666244Z","steps":["trace[649732192] 'agreement among raft nodes before linearized reading' (duration: 531.501406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:30.666294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.649712ms","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-03-04T01:24:30.666331Z","caller":"traceutil/trace.go:171","msg":"trace[1979921094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:771; }","duration":"863.701634ms","start":"2026-03-04T01:24:29.802619Z","end":"2026-03-04T01:24:30.666321Z","steps":["trace[1979921094] 'agreement among raft nodes before linearized reading' (duration: 862.800932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:30.666343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:30.133944Z","time spent":"532.389017ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T01:24:30.66636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:29.802604Z","time spent":"863.751365ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T01:24:30.87694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.818489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:24:30.877161Z","caller":"traceutil/trace.go:171","msg":"trace[701977675] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"205.484952ms","start":"2026-03-04T01:24:30.671658Z","end":"2026-03-04T01:24:30.877143Z","steps":["trace[701977675] 'process raft request' (duration: 205.442661ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:30.877316Z","caller":"traceutil/trace.go:171","msg":"trace[1515709456] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"206.71908ms","start":"2026-03-04T01:24:30.670589Z","end":"2026-03-04T01:24:30.877308Z","steps":["trace[1515709456] 'process raft request' (duration: 49.275827ms)","trace[1515709456] 'compare' (duration: 156.689416ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:24:30.877372Z","caller":"traceutil/trace.go:171","msg":"trace[1670755440] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"205.713197ms","start":"2026-03-04T01:24:30.671653Z","end":"2026-03-04T01:24:30.877367Z","steps":["trace[1670755440] 'process raft request' (duration: 205.40124ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:40.832123Z","caller":"traceutil/trace.go:171","msg":"trace[425363013] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"107.473171ms","start":"2026-03-04T01:24:40.724616Z","end":"2026-03-04T01:24:40.832089Z","steps":["trace[425363013] 'process raft request' (duration: 107.226875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:41.072232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.731497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T01:24:41.072347Z","caller":"traceutil/trace.go:171","msg":"trace[1511939918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:850; }","duration":"124.910462ms","start":"2026-03-04T01:24:40.947409Z","end":"2026-03-04T01:24:41.07232Z","steps":["trace[1511939918] 'range keys from in-memory index tree' (duration: 124.564094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:41.072327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.114225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-04T01:24:41.072355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.880819ms","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-03-04T01:24:41.072479Z","caller":"traceutil/trace.go:171","msg":"trace[1211463709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:850; }","duration":"124.038072ms","start":"2026-03-04T01:24:40.948408Z","end":"2026-03-04T01:24:41.072446Z","steps":["trace[1211463709] 'range keys from in-memory index tree' (duration: 123.671854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:41.07258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.173268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-04T01:24:41.072605Z","caller":"traceutil/trace.go:171","msg":"trace[640682814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:850; }","duration":"125.23726ms","start":"2026-03-04T01:24:40.947363Z","end":"2026-03-04T01:24:41.0726Z","steps":["trace[640682814] 'range keys from in-memory index tree' (duration: 124.987534ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:41.072415Z","caller":"traceutil/trace.go:171","msg":"trace[289202034] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:850; }","duration":"108.245057ms","start":"2026-03-04T01:24:40.964151Z","end":"2026-03-04T01:24:41.072396Z","steps":["trace[289202034] 'count revisions from in-memory index tree' (duration: 108.032932ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:41.321761Z","caller":"traceutil/trace.go:171","msg":"trace[65961640] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:888; }","duration":"186.838891ms","start":"2026-03-04T01:24:41.134896Z","end":"2026-03-04T01:24:41.321734Z","steps":["trace[65961640] 'read index received' (duration: 185.838259ms)","trace[65961640] 'applied index is now lower than readState.Index' (duration: 999.012µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:24:41.322038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.083537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:24:41.322136Z","caller":"traceutil/trace.go:171","msg":"trace[1491470645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:853; }","duration":"187.276831ms","start":"2026-03-04T01:24:41.134838Z","end":"2026-03-04T01:24:41.322115Z","steps":["trace[1491470645] 'agreement among raft nodes before linearized reading' (duration: 187.079376ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:41.322381Z","caller":"traceutil/trace.go:171","msg":"trace[1975517314] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"244.833731ms","start":"2026-03-04T01:24:41.077536Z","end":"2026-03-04T01:24:41.32237Z","steps":["trace[1975517314] 'process raft request' (duration: 243.278584ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:41.32239Z","caller":"traceutil/trace.go:171","msg":"trace[745802214] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"244.743899ms","start":"2026-03-04T01:24:41.077634Z","end":"2026-03-04T01:24:41.322378Z","steps":["trace[745802214] 'process raft request' (duration: 244.054182ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:42.814233Z","caller":"traceutil/trace.go:171","msg":"trace[919166456] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"106.250912ms","start":"2026-03-04T01:24:42.707948Z","end":"2026-03-04T01:24:42.814199Z","steps":["trace[919166456] 'process raft request' (duration: 69.799811ms)","trace[919166456] 'compare' (duration: 36.229826ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:24:43.609008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594527167734375829,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-04T01:24:43.642216Z","caller":"traceutil/trace.go:171","msg":"trace[1647224534] linearizableReadLoop","detail":"{readStateIndex:894; appliedIndex:893; }","duration":"533.742348ms","start":"2026-03-04T01:24:43.108444Z","end":"2026-03-04T01:24:43.642186Z","steps":["trace[1647224534] 'read index received' (duration: 533.460952ms)","trace[1647224534] 'applied index is now lower than readState.Index' (duration: 280.536µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:24:43.642619Z","caller":"traceutil/trace.go:171","msg":"trace[1573547080] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"801.917967ms","start":"2026-03-04T01:24:42.840692Z","end":"2026-03-04T01:24:43.64261Z","steps":["trace[1573547080] 'process raft request' (duration: 801.297993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:43.642741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:42.84066Z","time spent":"801.989279ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:24:43.642999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.557877ms","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-03-04T01:24:43.64306Z","caller":"traceutil/trace.go:171","msg":"trace[1959172144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:856; }","duration":"534.67744ms","start":"2026-03-04T01:24:43.108374Z","end":"2026-03-04T01:24:43.643052Z","steps":["trace[1959172144] 'agreement among raft nodes before linearized reading' (duration: 534.521606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:43.643081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:43.108344Z","time spent":"534.73268ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T01:24:43.643205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.365962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:24:43.643229Z","caller":"traceutil/trace.go:171","msg":"trace[801609297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:856; }","duration":"511.441754ms","start":"2026-03-04T01:24:43.131781Z","end":"2026-03-04T01:24:43.643223Z","steps":["trace[801609297] 'agreement among raft nodes before linearized reading' (duration: 511.395803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:43.643247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:43.131771Z","time spent":"511.471934ms","remote":"127.0.0.1:57738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T01:24:43.643421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.251926ms","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-03-04T01:24:43.643446Z","caller":"traceutil/trace.go:171","msg":"trace[13797157] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:856; }","duration":"312.277417ms","start":"2026-03-04T01:24:43.331163Z","end":"2026-03-04T01:24:43.64344Z","steps":["trace[13797157] 'agreement among raft nodes before linearized reading' (duration: 312.214525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:43.643461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:43.331142Z","time spent":"312.315997ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-04T01:24:43.643568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.090956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T01:24:43.64359Z","caller":"traceutil/trace.go:171","msg":"trace[1847251253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:856; }","duration":"316.131516ms","start":"2026-03-04T01:24:43.327454Z","end":"2026-03-04T01:24:43.643585Z","steps":["trace[1847251253] 'agreement among raft nodes before linearized reading' (duration: 316.072455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:43.643604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:43.327444Z","time spent":"316.156786ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-04T01:24:43.64373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.078007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-04T01:24:43.643751Z","caller":"traceutil/trace.go:171","msg":"trace[2115796731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"317.16515ms","start":"2026-03-04T01:24:43.326581Z","end":"2026-03-04T01:24:43.643746Z","steps":["trace[2115796731] 'agreement among raft nodes before linearized reading' (duration: 317.109708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:43.643764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:43.326544Z","time spent":"317.216321ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T01:24:43.643818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.248928ms","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-03-04T01:24:43.643836Z","caller":"traceutil/trace.go:171","msg":"trace[778159536] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:856; }","duration":"319.298819ms","start":"2026-03-04T01:24:43.324533Z","end":"2026-03-04T01:24:43.643832Z","steps":["trace[778159536] 'agreement among raft nodes before linearized reading' (duration: 319.266058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:24:43.643852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:24:43.324508Z","time spent":"319.34016ms","remote":"127.0.0.1:58124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-03-04T01:24:58.337067Z","caller":"traceutil/trace.go:171","msg":"trace[1234207300] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"100.431218ms","start":"2026-03-04T01:24:58.236597Z","end":"2026-03-04T01:24:58.337028Z","steps":["trace[1234207300] 'process raft request' (duration: 94.583363ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:24:59.032921Z","caller":"traceutil/trace.go:171","msg":"trace[719433193] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"175.552582ms","start":"2026-03-04T01:24:58.857343Z","end":"2026-03-04T01:24:59.032896Z","steps":["trace[719433193] 'process raft request' (duration: 175.388328ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:00.055231Z","caller":"traceutil/trace.go:171","msg":"trace[640447739] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"242.354664ms","start":"2026-03-04T01:24:59.81285Z","end":"2026-03-04T01:25:00.055204Z","steps":["trace[640447739] 'read index received' (duration: 242.097147ms)","trace[640447739] 'applied index is now lower than readState.Index' (duration: 256.747µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:00.055313Z","caller":"traceutil/trace.go:171","msg":"trace[178960524] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"254.201367ms","start":"2026-03-04T01:24:59.801067Z","end":"2026-03-04T01:25:00.055269Z","steps":["trace[178960524] 'process raft request' (duration: 253.96172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:00.055479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.817553ms","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":"warn","ts":"2026-03-04T01:25:00.055476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.60881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T01:25:00.055526Z","caller":"traceutil/trace.go:171","msg":"trace[304363296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"235.892115ms","start":"2026-03-04T01:24:59.819623Z","end":"2026-03-04T01:25:00.055515Z","steps":["trace[304363296] 'agreement among raft nodes before linearized reading' (duration: 235.744032ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:00.055545Z","caller":"traceutil/trace.go:171","msg":"trace[2062962035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:921; }","duration":"242.741142ms","start":"2026-03-04T01:24:59.812784Z","end":"2026-03-04T01:25:00.055525Z","steps":["trace[2062962035] 'agreement among raft nodes before linearized reading' (duration: 242.524238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:00.055729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.564896ms","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-03-04T01:25:00.055744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.064878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-04T01:25:00.055763Z","caller":"traceutil/trace.go:171","msg":"trace[992768459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"235.606788ms","start":"2026-03-04T01:24:59.820149Z","end":"2026-03-04T01:25:00.055755Z","steps":["trace[992768459] 'agreement among raft nodes before linearized reading' (duration: 235.515866ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:00.055772Z","caller":"traceutil/trace.go:171","msg":"trace[8259092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"236.09862ms","start":"2026-03-04T01:24:59.819664Z","end":"2026-03-04T01:25:00.055762Z","steps":["trace[8259092] 'agreement among raft nodes before linearized reading' (duration: 236.014018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:00.688343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.476644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:25:00.688479Z","caller":"traceutil/trace.go:171","msg":"trace[1119877346] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"628.581167ms","start":"2026-03-04T01:25:00.059873Z","end":"2026-03-04T01:25:00.688454Z","steps":["trace[1119877346] 'process raft request' (duration: 457.917098ms)","trace[1119877346] 'compare' (duration: 170.344871ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:25:00.688561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:00.059845Z","time spent":"628.676159ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:25:00.713765Z","caller":"traceutil/trace.go:171","msg":"trace[975939373] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"652.657471ms","start":"2026-03-04T01:25:00.061083Z","end":"2026-03-04T01:25:00.713741Z","steps":["trace[975939373] 'process raft request' (duration: 652.486037ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:00.713883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:00.061068Z","time spent":"652.758124ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:25:00.717561Z","caller":"traceutil/trace.go:171","msg":"trace[357595697] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"656.334527ms","start":"2026-03-04T01:25:00.061157Z","end":"2026-03-04T01:25:00.717492Z","steps":["trace[357595697] 'process raft request' (duration: 652.539539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:00.71777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.059068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:25:00.717829Z","caller":"traceutil/trace.go:171","msg":"trace[1661043464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"584.15668ms","start":"2026-03-04T01:25:00.133662Z","end":"2026-03-04T01:25:00.717818Z","steps":["trace[1661043464] 'agreement among raft nodes before linearized reading' (duration: 584.019317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:00.717872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:00.13365Z","time spent":"584.210361ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-04T01:25:00.718186Z","caller":"traceutil/trace.go:171","msg":"trace[900124143] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"656.255324ms","start":"2026-03-04T01:25:00.06192Z","end":"2026-03-04T01:25:00.718175Z","steps":["trace[900124143] 'process raft request' (duration: 655.476817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:00.718289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:00.061892Z","time spent":"656.331286ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:25:00.718646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:00.06113Z","time spent":"656.730275ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:25:00.717621Z","caller":"traceutil/trace.go:171","msg":"trace[1639025235] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:962; }","duration":"583.905515ms","start":"2026-03-04T01:25:00.133686Z","end":"2026-03-04T01:25:00.717591Z","steps":["trace[1639025235] 'read index received' (duration: 384.125635ms)","trace[1639025235] 'applied index is now lower than readState.Index' (duration: 199.77867ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:03.059812Z","caller":"traceutil/trace.go:171","msg":"trace[1626276275] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"366.208282ms","start":"2026-03-04T01:25:02.69357Z","end":"2026-03-04T01:25:03.059779Z","steps":["trace[1626276275] 'read index received' (duration: 365.986757ms)","trace[1626276275] 'applied index is now lower than readState.Index' (duration: 219.405µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:25:03.059988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:02.589178Z","time spent":"470.805064ms","remote":"127.0.0.1:57774","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-04T01:25:03.060431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.454386ms","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-03-04T01:25:03.060553Z","caller":"traceutil/trace.go:171","msg":"trace[1283187933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"336.572008ms","start":"2026-03-04T01:25:02.723936Z","end":"2026-03-04T01:25:03.060508Z","steps":["trace[1283187933] 'agreement among raft nodes before linearized reading' (duration: 336.292502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:03.060619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.636473ms","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-03-04T01:25:03.060678Z","caller":"traceutil/trace.go:171","msg":"trace[1290964696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"334.756676ms","start":"2026-03-04T01:25:02.725908Z","end":"2026-03-04T01:25:03.060665Z","steps":["trace[1290964696] 'agreement among raft nodes before linearized reading' (duration: 334.48122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:03.060726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:02.725884Z","time spent":"334.829267ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-04T01:25:03.060729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.715861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-04T01:25:03.060769Z","caller":"traceutil/trace.go:171","msg":"trace[836421637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"336.793483ms","start":"2026-03-04T01:25:02.723965Z","end":"2026-03-04T01:25:03.060758Z","steps":["trace[836421637] 'agreement among raft nodes before linearized reading' (duration: 336.691651ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:03.060802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:02.723935Z","time spent":"336.860895ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T01:25:03.06098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.41714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T01:25:03.061023Z","caller":"traceutil/trace.go:171","msg":"trace[2083070744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:927; }","duration":"367.492482ms","start":"2026-03-04T01:25:02.693516Z","end":"2026-03-04T01:25:03.061009Z","steps":["trace[2083070744] 'agreement among raft nodes before linearized reading' (duration: 367.389038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:03.061053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:02.693496Z","time spent":"367.550402ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-04T01:25:03.060631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:02.723872Z","time spent":"336.743802ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-04T01:25:03.28049Z","caller":"traceutil/trace.go:171","msg":"trace[1176925652] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"218.77295ms","start":"2026-03-04T01:25:03.061681Z","end":"2026-03-04T01:25:03.280454Z","steps":["trace[1176925652] 'process raft request' (duration: 166.654866ms)","trace[1176925652] 'compare' (duration: 51.94203ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:03.352194Z","caller":"traceutil/trace.go:171","msg":"trace[500575230] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:969; }","duration":"218.776591ms","start":"2026-03-04T01:25:03.133392Z","end":"2026-03-04T01:25:03.352169Z","steps":["trace[500575230] 'read index received' (duration: 94.960852ms)","trace[500575230] 'applied index is now lower than readState.Index' (duration: 123.813859ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:03.352307Z","caller":"traceutil/trace.go:171","msg":"trace[455038604] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"287.154559ms","start":"2026-03-04T01:25:03.065116Z","end":"2026-03-04T01:25:03.352271Z","steps":["trace[455038604] 'process raft request' (duration: 286.897533ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:03.352373Z","caller":"traceutil/trace.go:171","msg":"trace[1414849838] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"280.498797ms","start":"2026-03-04T01:25:03.071858Z","end":"2026-03-04T01:25:03.352357Z","steps":["trace[1414849838] 'process raft request' (duration: 280.210979ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:03.352517Z","caller":"traceutil/trace.go:171","msg":"trace[1689950682] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"287.486737ms","start":"2026-03-04T01:25:03.065018Z","end":"2026-03-04T01:25:03.352505Z","steps":["trace[1689950682] 'process raft request' (duration: 286.882312ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:03.352528Z","caller":"traceutil/trace.go:171","msg":"trace[684748264] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"280.018024ms","start":"2026-03-04T01:25:03.072495Z","end":"2026-03-04T01:25:03.352513Z","steps":["trace[684748264] 'process raft request' (duration: 279.620384ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:03.352622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.211192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:25:03.352681Z","caller":"traceutil/trace.go:171","msg":"trace[172899210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"219.336995ms","start":"2026-03-04T01:25:03.13333Z","end":"2026-03-04T01:25:03.352667Z","steps":["trace[172899210] 'agreement among raft nodes before linearized reading' (duration: 219.224412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:03.594667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.681009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:25:03.594773Z","caller":"traceutil/trace.go:171","msg":"trace[1171623705] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"234.304165ms","start":"2026-03-04T01:25:03.360452Z","end":"2026-03-04T01:25:03.594756Z","steps":["trace[1171623705] 'read index received' (duration: 121.39926ms)","trace[1171623705] 'applied index is now lower than readState.Index' (duration: 112.903735ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:03.594902Z","caller":"traceutil/trace.go:171","msg":"trace[1932095414] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"235.016981ms","start":"2026-03-04T01:25:03.359778Z","end":"2026-03-04T01:25:03.594794Z","steps":["trace[1932095414] 'process raft request' (duration: 122.098156ms)","trace[1932095414] 'compare' (duration: 112.544666ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:25:03.595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.52575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-04T01:25:03.595088Z","caller":"traceutil/trace.go:171","msg":"trace[1098717777] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:933; }","duration":"234.647942ms","start":"2026-03-04T01:25:03.360421Z","end":"2026-03-04T01:25:03.595069Z","steps":["trace[1098717777] 'agreement among raft nodes before linearized reading' (duration: 234.419277ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:03.868889Z","caller":"traceutil/trace.go:171","msg":"trace[1337444723] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"203.205315ms","start":"2026-03-04T01:25:03.665658Z","end":"2026-03-04T01:25:03.868863Z","steps":["trace[1337444723] 'read index received' (duration: 203.018961ms)","trace[1337444723] 'applied index is now lower than readState.Index' (duration: 185.144µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:03.868935Z","caller":"traceutil/trace.go:171","msg":"trace[1007062097] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"243.125436ms","start":"2026-03-04T01:25:03.625764Z","end":"2026-03-04T01:25:03.86889Z","steps":["trace[1007062097] 'process raft request' (duration: 242.977062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:03.869142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.462371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-04T01:25:03.869185Z","caller":"traceutil/trace.go:171","msg":"trace[1913674361] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:935; }","duration":"203.556883ms","start":"2026-03-04T01:25:03.665618Z","end":"2026-03-04T01:25:03.869175Z","steps":["trace[1913674361] 'agreement among raft nodes before linearized reading' (duration: 203.338088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:04.115034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.394986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:25:04.115335Z","caller":"traceutil/trace.go:171","msg":"trace[79069164] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"447.235721ms","start":"2026-03-04T01:25:03.668088Z","end":"2026-03-04T01:25:04.115324Z","steps":["trace[79069164] 'process raft request' (duration: 276.485456ms)","trace[79069164] 'compare' (duration: 170.261224ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:25:04.115373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:03.66808Z","time spent":"447.278452ms","remote":"127.0.0.1:57826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T01:25:04.115445Z","caller":"traceutil/trace.go:171","msg":"trace[1201502824] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"240.440584ms","start":"2026-03-04T01:25:03.875Z","end":"2026-03-04T01:25:04.11544Z","steps":["trace[1201502824] 'process raft request' (duration: 240.112577ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:25:04.115546Z","caller":"traceutil/trace.go:171","msg":"trace[88472533] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"240.25446ms","start":"2026-03-04T01:25:03.875287Z","end":"2026-03-04T01:25:04.115542Z","steps":["trace[88472533] 'process raft request' (duration: 239.847521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:05.152205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.947846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:25:05.15231Z","caller":"traceutil/trace.go:171","msg":"trace[1361395316] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"1.032144972s","start":"2026-03-04T01:25:04.120144Z","end":"2026-03-04T01:25:05.152289Z","steps":["trace[1361395316] 'process raft request' (duration: 510.012782ms)","trace[1361395316] 'compare' (duration: 521.621448ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:25:05.152384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:04.120126Z","time spent":"1.032229024s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:25:05.375718Z","caller":"traceutil/trace.go:171","msg":"trace[2074461987] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"247.442715ms","start":"2026-03-04T01:25:05.128255Z","end":"2026-03-04T01:25:05.375698Z","steps":["trace[2074461987] 'read index received' (duration: 236.067635ms)","trace[2074461987] 'applied index is now lower than readState.Index' (duration: 11.37419ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:05.37592Z","caller":"traceutil/trace.go:171","msg":"trace[886195930] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"657.475337ms","start":"2026-03-04T01:25:04.718435Z","end":"2026-03-04T01:25:05.375911Z","steps":["trace[886195930] 'process raft request' (duration: 645.952704ms)","trace[886195930] 'compare' (duration: 11.215396ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:25:05.376003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:04.718415Z","time spent":"657.527078ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T01:25:05.376023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.134444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:25:05.376089Z","caller":"traceutil/trace.go:171","msg":"trace[971547903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"242.225285ms","start":"2026-03-04T01:25:05.133847Z","end":"2026-03-04T01:25:05.376072Z","steps":["trace[971547903] 'agreement among raft nodes before linearized reading' (duration: 242.126643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:05.37642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.160751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:25:05.376456Z","caller":"traceutil/trace.go:171","msg":"trace[2032521239] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:940; }","duration":"248.233813ms","start":"2026-03-04T01:25:05.128214Z","end":"2026-03-04T01:25:05.376448Z","steps":["trace[2032521239] 'agreement among raft nodes before linearized reading' (duration: 248.184241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:13.967341Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594527167734376126,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T01:25:14.467953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594527167734376126,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-04T01:25:14.673512Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.418630028s","expected-duration":"1s"} {"level":"info","ts":"2026-03-04T01:25:14.673915Z","caller":"traceutil/trace.go:171","msg":"trace[18920660] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"1.206632833s","start":"2026-03-04T01:25:13.467254Z","end":"2026-03-04T01:25:14.673886Z","steps":["trace[18920660] 'read index received' (duration: 1.206384137s)","trace[18920660] 'applied index is now lower than readState.Index' (duration: 247.516µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:25:14.67408Z","caller":"traceutil/trace.go:171","msg":"trace[1461595317] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"1.419235882s","start":"2026-03-04T01:25:13.25476Z","end":"2026-03-04T01:25:14.673996Z","steps":["trace[1461595317] 'process raft request' (duration: 1.418947066s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:14.674345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207083852s","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":"warn","ts":"2026-03-04T01:25:14.67435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:13.254738Z","time spent":"1.419465417s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T01:25:14.674412Z","caller":"traceutil/trace.go:171","msg":"trace[1421656518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:999; }","duration":"1.207177985s","start":"2026-03-04T01:25:13.467215Z","end":"2026-03-04T01:25:14.674393Z","steps":["trace[1421656518] 'agreement among raft nodes before linearized reading' (duration: 1.206778616s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:14.674465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:13.467198Z","time spent":"1.207253596s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-04T01:25:14.67497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.896255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-04T01:25:14.675095Z","caller":"traceutil/trace.go:171","msg":"trace[1631500036] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:999; }","duration":"754.060469ms","start":"2026-03-04T01:25:13.921013Z","end":"2026-03-04T01:25:14.675074Z","steps":["trace[1631500036] 'agreement among raft nodes before linearized reading' (duration: 753.818854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:14.675195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:13.920995Z","time spent":"754.188282ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-04T01:25:14.675252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180924655s","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-03-04T01:25:14.675317Z","caller":"traceutil/trace.go:171","msg":"trace[818548605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:999; }","duration":"1.181020747s","start":"2026-03-04T01:25:13.494279Z","end":"2026-03-04T01:25:14.675299Z","steps":["trace[818548605] 'agreement among raft nodes before linearized reading' (duration: 1.180872444s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:14.675368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:13.494258Z","time spent":"1.181095409s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-04T01:25:14.675539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.449169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-04T01:25:14.675612Z","caller":"traceutil/trace.go:171","msg":"trace[1375475072] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:999; }","duration":"523.562342ms","start":"2026-03-04T01:25:14.152038Z","end":"2026-03-04T01:25:14.6756Z","steps":["trace[1375475072] 'agreement among raft nodes before linearized reading' (duration: 523.430899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:14.675692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:14.152017Z","time spent":"523.664464ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-04T01:25:14.675687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097626026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-04T01:25:14.675888Z","caller":"traceutil/trace.go:171","msg":"trace[811493621] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:999; }","duration":"1.097895012s","start":"2026-03-04T01:25:13.577976Z","end":"2026-03-04T01:25:14.675871Z","steps":["trace[811493621] 'agreement among raft nodes before linearized reading' (duration: 1.097619696s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:14.675971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:13.577954Z","time spent":"1.098004554s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-04T01:25:14.675762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.896901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T01:25:14.676109Z","caller":"traceutil/trace.go:171","msg":"trace[930706447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:999; }","duration":"542.281139ms","start":"2026-03-04T01:25:14.13381Z","end":"2026-03-04T01:25:14.676091Z","steps":["trace[930706447] 'agreement among raft nodes before linearized reading' (duration: 541.87352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:14.676157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:14.133797Z","time spent":"542.348141ms","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-04T01:25:14.675825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181876108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-04T01:25:14.676221Z","caller":"traceutil/trace.go:171","msg":"trace[816301380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"1.182293756s","start":"2026-03-04T01:25:13.49391Z","end":"2026-03-04T01:25:14.676204Z","steps":["trace[816301380] 'agreement among raft nodes before linearized reading' (duration: 1.181832646s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:14.676308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:13.493901Z","time spent":"1.182386529s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-04T01:25:14.677069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183799861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-04T01:25:14.677122Z","caller":"traceutil/trace.go:171","msg":"trace[28445191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:999; }","duration":"1.183897704s","start":"2026-03-04T01:25:13.493212Z","end":"2026-03-04T01:25:14.67711Z","steps":["trace[28445191] 'agreement among raft nodes before linearized reading' (duration: 1.181868347s)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:25:14.677153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T01:25:13.493193Z","time spent":"1.183952155s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-04T01:25:57.591091Z","caller":"traceutil/trace.go:171","msg":"trace[1508933945] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"129.612446ms","start":"2026-03-04T01:25:57.461452Z","end":"2026-03-04T01:25:57.591064Z","steps":["trace[1508933945] 'process raft request' (duration: 129.465493ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:26:35.269748Z","caller":"traceutil/trace.go:171","msg":"trace[468807407] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1391; }","duration":"162.201276ms","start":"2026-03-04T01:26:35.107514Z","end":"2026-03-04T01:26:35.269715Z","steps":["trace[468807407] 'read index received' (duration: 77.810932ms)","trace[468807407] 'applied index is now lower than readState.Index' (duration: 84.388894ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:26:35.269871Z","caller":"traceutil/trace.go:171","msg":"trace[381080408] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"172.917444ms","start":"2026-03-04T01:26:35.096924Z","end":"2026-03-04T01:26:35.269842Z","steps":["trace[381080408] 'process raft request' (duration: 131.404413ms)","trace[381080408] 'compare' (duration: 41.253705ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:26:35.270187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.652336ms","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-03-04T01:26:35.270309Z","caller":"traceutil/trace.go:171","msg":"trace[614810673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1333; }","duration":"162.774948ms","start":"2026-03-04T01:26:35.107481Z","end":"2026-03-04T01:26:35.270256Z","steps":["trace[614810673] 'agreement among raft nodes before linearized reading' (duration: 162.516152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:26:35.270368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.504179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-04T01:26:35.270361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.26353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-04T01:26:35.270452Z","caller":"traceutil/trace.go:171","msg":"trace[2133732453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1333; }","duration":"158.643352ms","start":"2026-03-04T01:26:35.111789Z","end":"2026-03-04T01:26:35.270432Z","steps":["trace[2133732453] 'agreement among raft nodes before linearized reading' (duration: 158.446918ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:26:35.270472Z","caller":"traceutil/trace.go:171","msg":"trace[203231056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1333; }","duration":"160.443264ms","start":"2026-03-04T01:26:35.110002Z","end":"2026-03-04T01:26:35.270445Z","steps":["trace[203231056] 'agreement among raft nodes before linearized reading' (duration: 160.077286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:27:55.873539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.886736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T01:27:55.873672Z","caller":"traceutil/trace.go:171","msg":"trace[479299108] linearizableReadLoop","detail":"{readStateIndex:1737; appliedIndex:1736; }","duration":"155.2421ms","start":"2026-03-04T01:27:55.718409Z","end":"2026-03-04T01:27:55.873651Z","steps":["trace[479299108] 'read index received' (duration: 27.992485ms)","trace[479299108] 'applied index is now lower than readState.Index' (duration: 127.248345ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:27:55.873999Z","caller":"traceutil/trace.go:171","msg":"trace[1318741025] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"190.659628ms","start":"2026-03-04T01:27:55.683296Z","end":"2026-03-04T01:27:55.873955Z","steps":["trace[1318741025] 'process raft request' (duration: 63.226989ms)","trace[1318741025] 'compare' (duration: 126.727343ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:27:55.873997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.566548ms","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-03-04T01:27:55.874123Z","caller":"traceutil/trace.go:171","msg":"trace[326590399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1661; }","duration":"155.749582ms","start":"2026-03-04T01:27:55.718349Z","end":"2026-03-04T01:27:55.874099Z","steps":["trace[326590399] 'agreement among raft nodes before linearized reading' (duration: 155.415314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:27:55.874126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.411139ms","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-03-04T01:27:55.874204Z","caller":"traceutil/trace.go:171","msg":"trace[1019716782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1661; }","duration":"101.545182ms","start":"2026-03-04T01:27:55.772636Z","end":"2026-03-04T01:27:55.874182Z","steps":["trace[1019716782] 'agreement among raft nodes before linearized reading' (duration: 101.333257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T01:27:55.874354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.713785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-04T01:27:55.874387Z","caller":"traceutil/trace.go:171","msg":"trace[367367021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1661; }","duration":"101.779237ms","start":"2026-03-04T01:27:55.772597Z","end":"2026-03-04T01:27:55.874376Z","steps":["trace[367367021] 'agreement among raft nodes before linearized reading' (duration: 101.202864ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:27:55.980569Z","caller":"traceutil/trace.go:171","msg":"trace[687017329] transaction","detail":"{read_only:false; response_revision:1664; number_of_response:1; }","duration":"101.539111ms","start":"2026-03-04T01:27:55.87899Z","end":"2026-03-04T01:27:55.980529Z","steps":["trace[687017329] 'process raft request' (duration: 101.421919ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:27:55.980505Z","caller":"traceutil/trace.go:171","msg":"trace[1067736665] transaction","detail":"{read_only:false; response_revision:1663; number_of_response:1; }","duration":"101.821028ms","start":"2026-03-04T01:27:55.878659Z","end":"2026-03-04T01:27:55.98048Z","steps":["trace[1067736665] 'process raft request' (duration: 97.54635ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:28:30.331309Z","caller":"traceutil/trace.go:171","msg":"trace[567821819] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1918; }","duration":"103.106568ms","start":"2026-03-04T01:28:30.228168Z","end":"2026-03-04T01:28:30.331275Z","steps":["trace[567821819] 'read index received' (duration: 43.7879ms)","trace[567821819] 'applied index is now lower than readState.Index' (duration: 59.317438ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T01:28:30.331556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.348853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-03-04T01:28:30.331606Z","caller":"traceutil/trace.go:171","msg":"trace[477728896] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1835; }","duration":"103.439505ms","start":"2026-03-04T01:28:30.228151Z","end":"2026-03-04T01:28:30.33159Z","steps":["trace[477728896] 'agreement among raft nodes before linearized reading' (duration: 103.24048ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T01:28:30.331886Z","caller":"traceutil/trace.go:171","msg":"trace[2060890569] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"104.760416ms","start":"2026-03-04T01:28:30.226607Z","end":"2026-03-04T01:28:30.331368Z","steps":["trace[2060890569] 'process raft request' (duration: 45.510189ms)","trace[2060890569] 'compare' (duration: 58.95908ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T01:28:30.601302Z","caller":"traceutil/trace.go:171","msg":"trace[456831179] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"264.590283ms","start":"2026-03-04T01:28:30.336683Z","end":"2026-03-04T01:28:30.601273Z","steps":["trace[456831179] 'process raft request' (duration: 199.01467ms)","trace[456831179] 'compare' (duration: 65.261946ms)"],"step_count":2}