{"level":"warn","ts":"2026-02-25T02:15:14.900883Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T02:15:14.90096Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.41:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.41:2380","--initial-cluster=instance=https://199.204.45.41:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.41:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.41:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-25T02:15:14.901018Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T02:15:14.901031Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-25T02:15:14.90105Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T02:15:14.901655Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"]} {"level":"info","ts":"2026-02-25T02:15:14.901727Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.41:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-25T02:15:14.90573Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.809232ms"} {"level":"info","ts":"2026-02-25T02:15:14.915239Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"17dd22a2661aae79","cluster-id":"c5133f40b27eeb3"} {"level":"info","ts":"2026-02-25T02:15:14.915337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:15:14.915355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 0"} {"level":"info","ts":"2026-02-25T02:15:14.915368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 17dd22a2661aae79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:15:14.915381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 1"} {"level":"info","ts":"2026-02-25T02:15:14.915425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"warn","ts":"2026-02-25T02:15:14.922358Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:15:14.924281Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:15:14.925597Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-25T02:15:14.929392Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"17dd22a2661aae79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:15:14.930232Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"17dd22a2661aae79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:15:14.930363Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T02:15:14.930415Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T02:15:14.93043Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T02:15:14.931294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"info","ts":"2026-02-25T02:15:14.931892Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","added-peer-id":"17dd22a2661aae79","added-peer-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-25T02:15:14.934735Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T02:15:14.934929Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"17dd22a2661aae79","initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:15:14.934957Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:15:14.935058Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-25T02:15:14.935068Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-25T02:15:15.516432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:15:15.516573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:15:15.516617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgPreVoteResp from 17dd22a2661aae79 at term 1"} {"level":"info","ts":"2026-02-25T02:15:15.516643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:15:15.516656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgVoteResp from 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-25T02:15:15.516683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became leader at term 2"} {"level":"info","ts":"2026-02-25T02:15:15.516705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 17dd22a2661aae79 elected leader 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-25T02:15:15.551713Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:15:15.553858Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:15:15.553854Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"17dd22a2661aae79","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.41:2379]}","request-path":"/0/members/17dd22a2661aae79/attributes","cluster-id":"c5133f40b27eeb3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:15:15.553972Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:15:15.554336Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:15:15.554389Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:15:15.55529Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:15:15.555425Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:15:15.555506Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:15:15.558575Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.41:2379"} {"level":"info","ts":"2026-02-25T02:15:15.560325Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-25T02:15:18.301762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251988147758098,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:15:18.567619Z","caller":"traceutil/trace.go:171","msg":"trace[1207994458] linearizableReadLoop","detail":"{readStateIndex:116; appliedIndex:115; }","duration":"766.548758ms","start":"2026-02-25T02:15:17.801039Z","end":"2026-02-25T02:15:18.567587Z","steps":["trace[1207994458] 'read index received' (duration: 762.380106ms)","trace[1207994458] 'applied index is now lower than readState.Index' (duration: 4.167422ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:18.567705Z","caller":"traceutil/trace.go:171","msg":"trace[635563042] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"767.804722ms","start":"2026-02-25T02:15:17.799849Z","end":"2026-02-25T02:15:18.567654Z","steps":["trace[635563042] 'process raft request' (duration: 763.514877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:18.5679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.069919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T02:15:18.567931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.468665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T02:15:18.567984Z","caller":"traceutil/trace.go:171","msg":"trace[1166868343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:111; }","duration":"670.206703ms","start":"2026-02-25T02:15:17.89776Z","end":"2026-02-25T02:15:18.567967Z","steps":["trace[1166868343] 'agreement among raft nodes before linearized reading' (duration: 669.996487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:18.568016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:17.897739Z","time spent":"670.269634ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:15:18.568017Z","caller":"traceutil/trace.go:171","msg":"trace[493390109] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:111; }","duration":"100.608329ms","start":"2026-02-25T02:15:18.467389Z","end":"2026-02-25T02:15:18.567997Z","steps":["trace[493390109] 'agreement among raft nodes before linearized reading' (duration: 100.419704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:18.568117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.055802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T02:15:18.568169Z","caller":"traceutil/trace.go:171","msg":"trace[199882910] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:111; }","duration":"767.137774ms","start":"2026-02-25T02:15:17.801018Z","end":"2026-02-25T02:15:18.568155Z","steps":["trace[199882910] 'agreement among raft nodes before linearized reading' (duration: 766.734053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:18.568289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:17.799825Z","time spent":"767.960226ms","remote":"127.0.0.1:52790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:15:18.568302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:17.801006Z","time spent":"767.234106ms","remote":"127.0.0.1:52768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"info","ts":"2026-02-25T02:15:24.49471Z","caller":"traceutil/trace.go:171","msg":"trace[1481306029] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:348; }","duration":"421.608848ms","start":"2026-02-25T02:15:24.073074Z","end":"2026-02-25T02:15:24.494683Z","steps":["trace[1481306029] 'read index received' (duration: 421.490564ms)","trace[1481306029] 'applied index is now lower than readState.Index' (duration: 117.044µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:24.49483Z","caller":"traceutil/trace.go:171","msg":"trace[1061256422] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"855.593388ms","start":"2026-02-25T02:15:23.639205Z","end":"2026-02-25T02:15:24.494798Z","steps":["trace[1061256422] 'process raft request' (duration: 855.334721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:24.494915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.815263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T02:15:24.494956Z","caller":"traceutil/trace.go:171","msg":"trace[576430253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:335; }","duration":"421.923766ms","start":"2026-02-25T02:15:24.073022Z","end":"2026-02-25T02:15:24.494946Z","steps":["trace[576430253] 'agreement among raft nodes before linearized reading' (duration: 421.764151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:24.494979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:23.639186Z","time spent":"855.727472ms","remote":"127.0.0.1:52616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:15:24.494997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:24.073Z","time spent":"421.988988ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:15:24.969513Z","caller":"traceutil/trace.go:171","msg":"trace[1533784465] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:349; }","duration":"468.387228ms","start":"2026-02-25T02:15:24.501099Z","end":"2026-02-25T02:15:24.969486Z","steps":["trace[1533784465] 'read index received' (duration: 422.310506ms)","trace[1533784465] 'applied index is now lower than readState.Index' (duration: 46.075912ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:24.969644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.522481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T02:15:24.96968Z","caller":"traceutil/trace.go:171","msg":"trace[172534290] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:336; }","duration":"468.591833ms","start":"2026-02-25T02:15:24.501078Z","end":"2026-02-25T02:15:24.96967Z","steps":["trace[172534290] 'agreement among raft nodes before linearized reading' (duration: 468.49766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:24.969682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.143203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T02:15:24.969713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:24.501066Z","time spent":"468.640584ms","remote":"127.0.0.1:52616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"info","ts":"2026-02-25T02:15:24.969719Z","caller":"traceutil/trace.go:171","msg":"trace[2075390980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:336; }","duration":"105.214155ms","start":"2026-02-25T02:15:24.864491Z","end":"2026-02-25T02:15:24.969705Z","steps":["trace[2075390980] 'agreement among raft nodes before linearized reading' (duration: 105.149093ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:24.969781Z","caller":"traceutil/trace.go:171","msg":"trace[1729343663] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"470.252608ms","start":"2026-02-25T02:15:24.499312Z","end":"2026-02-25T02:15:24.969565Z","steps":["trace[1729343663] 'process raft request' (duration: 424.171856ms)","trace[1729343663] 'compare' (duration: 45.873876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:24.970145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:24.499293Z","time spent":"470.592646ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:25.497603Z","caller":"traceutil/trace.go:171","msg":"trace[492922801] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"179.375052ms","start":"2026-02-25T02:15:25.31818Z","end":"2026-02-25T02:15:25.497555Z","steps":["trace[492922801] 'process raft request' (duration: 136.24566ms)","trace[492922801] 'compare' (duration: 42.829584ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:25.497639Z","caller":"traceutil/trace.go:171","msg":"trace[1649006642] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"178.041227ms","start":"2026-02-25T02:15:25.319561Z","end":"2026-02-25T02:15:25.497602Z","steps":["trace[1649006642] 'process raft request' (duration: 177.890362ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.498009Z","caller":"traceutil/trace.go:171","msg":"trace[1058964513] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:364; }","duration":"158.713396ms","start":"2026-02-25T02:15:25.339263Z","end":"2026-02-25T02:15:25.497977Z","steps":["trace[1058964513] 'read index received' (duration: 115.041199ms)","trace[1058964513] 'applied index is now lower than readState.Index' (duration: 43.671057ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:25.498023Z","caller":"traceutil/trace.go:171","msg":"trace[1284049577] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"176.67617ms","start":"2026-02-25T02:15:25.321306Z","end":"2026-02-25T02:15:25.497982Z","steps":["trace[1284049577] 'process raft request' (duration: 176.243758ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.498061Z","caller":"traceutil/trace.go:171","msg":"trace[1360476727] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"174.812099ms","start":"2026-02-25T02:15:25.323229Z","end":"2026-02-25T02:15:25.498041Z","steps":["trace[1360476727] 'process raft request' (duration: 174.633715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.49822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.929321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T02:15:25.498303Z","caller":"traceutil/trace.go:171","msg":"trace[742389518] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:354; }","duration":"159.023094ms","start":"2026-02-25T02:15:25.339256Z","end":"2026-02-25T02:15:25.498279Z","steps":["trace[742389518] 'agreement among raft nodes before linearized reading' (duration: 158.846669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.498238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.549197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-25T02:15:25.498394Z","caller":"traceutil/trace.go:171","msg":"trace[433295517] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:354; }","duration":"107.744242ms","start":"2026-02-25T02:15:25.390631Z","end":"2026-02-25T02:15:25.498376Z","steps":["trace[433295517] 'agreement among raft nodes before linearized reading' (duration: 107.503796ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.725313Z","caller":"traceutil/trace.go:171","msg":"trace[114062049] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"132.698454ms","start":"2026-02-25T02:15:25.592585Z","end":"2026-02-25T02:15:25.725283Z","steps":["trace[114062049] 'process raft request' (duration: 132.651143ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.725317Z","caller":"traceutil/trace.go:171","msg":"trace[159916739] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"134.127032ms","start":"2026-02-25T02:15:25.591137Z","end":"2026-02-25T02:15:25.725264Z","steps":["trace[159916739] 'process raft request' (duration: 134.000829ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.72538Z","caller":"traceutil/trace.go:171","msg":"trace[1945003324] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"134.082641ms","start":"2026-02-25T02:15:25.591283Z","end":"2026-02-25T02:15:25.725365Z","steps":["trace[1945003324] 'process raft request' (duration: 133.897276ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.72537Z","caller":"traceutil/trace.go:171","msg":"trace[1147602600] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"134.344338ms","start":"2026-02-25T02:15:25.590989Z","end":"2026-02-25T02:15:25.725333Z","steps":["trace[1147602600] 'process raft request' (duration: 133.989169ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.968861Z","caller":"traceutil/trace.go:171","msg":"trace[319531059] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"375.74683ms","start":"2026-02-25T02:15:25.593078Z","end":"2026-02-25T02:15:25.968825Z","steps":["trace[319531059] 'process raft request' (duration: 331.860478ms)","trace[319531059] 'compare' (duration: 43.575854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:25.969068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.593065Z","time spent":"375.904565ms","remote":"127.0.0.1:52686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:25.969426Z","caller":"traceutil/trace.go:171","msg":"trace[1475751303] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"374.911277ms","start":"2026-02-25T02:15:25.594483Z","end":"2026-02-25T02:15:25.969394Z","steps":["trace[1475751303] 'process raft request' (duration: 374.25641ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.969489Z","caller":"traceutil/trace.go:171","msg":"trace[1951310092] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"374.952049ms","start":"2026-02-25T02:15:25.594519Z","end":"2026-02-25T02:15:25.969471Z","steps":["trace[1951310092] 'process raft request' (duration: 374.552978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.969589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.594506Z","time spent":"375.044382ms","remote":"127.0.0.1:52908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2864,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:25.969634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.594461Z","time spent":"375.076672ms","remote":"127.0.0.1:52768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:25.969754Z","caller":"traceutil/trace.go:171","msg":"trace[274010021] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"368.629979ms","start":"2026-02-25T02:15:25.601113Z","end":"2026-02-25T02:15:25.969743Z","steps":["trace[274010021] 'process raft request' (duration: 368.365832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.969816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.601102Z","time spent":"368.68236ms","remote":"127.0.0.1:52596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4241,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:25.969804Z","caller":"traceutil/trace.go:171","msg":"trace[497315094] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"372.487293ms","start":"2026-02-25T02:15:25.597292Z","end":"2026-02-25T02:15:25.96978Z","steps":["trace[497315094] 'process raft request' (duration: 372.100612ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.969824Z","caller":"traceutil/trace.go:171","msg":"trace[1479375121] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"238.554576ms","start":"2026-02-25T02:15:25.73125Z","end":"2026-02-25T02:15:25.969804Z","steps":["trace[1479375121] 'process raft request' (duration: 238.35643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.969907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.597279Z","time spent":"372.574555ms","remote":"127.0.0.1:52768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:25.969933Z","caller":"traceutil/trace.go:171","msg":"trace[1083907819] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:376; }","duration":"376.547842ms","start":"2026-02-25T02:15:25.593365Z","end":"2026-02-25T02:15:25.969913Z","steps":["trace[1083907819] 'read index received' (duration: 131.672106ms)","trace[1083907819] 'applied index is now lower than readState.Index' (duration: 244.874006ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:25.970098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.729115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T02:15:25.970117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.005254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-02-25T02:15:25.970152Z","caller":"traceutil/trace.go:171","msg":"trace[2060758013] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:372; }","duration":"237.066996ms","start":"2026-02-25T02:15:25.733073Z","end":"2026-02-25T02:15:25.97014Z","steps":["trace[2060758013] 'agreement among raft nodes before linearized reading' (duration: 237.006044ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.97015Z","caller":"traceutil/trace.go:171","msg":"trace[1136624538] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:372; }","duration":"380.837507ms","start":"2026-02-25T02:15:25.589297Z","end":"2026-02-25T02:15:25.970135Z","steps":["trace[1136624538] 'agreement among raft nodes before linearized reading' (duration: 380.715664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.970195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:25.589275Z","time spent":"380.90822ms","remote":"127.0.0.1:52616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-02-25T02:15:26.198078Z","caller":"traceutil/trace.go:171","msg":"trace[2088459279] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"208.836426ms","start":"2026-02-25T02:15:25.989214Z","end":"2026-02-25T02:15:26.19805Z","steps":["trace[2088459279] 'process raft request' (duration: 208.783615ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.198189Z","caller":"traceutil/trace.go:171","msg":"trace[1248433127] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"214.137458ms","start":"2026-02-25T02:15:25.984024Z","end":"2026-02-25T02:15:26.198161Z","steps":["trace[1248433127] 'process raft request' (duration: 213.922212ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.198248Z","caller":"traceutil/trace.go:171","msg":"trace[657501724] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"216.535413ms","start":"2026-02-25T02:15:25.981674Z","end":"2026-02-25T02:15:26.198209Z","steps":["trace[657501724] 'process raft request' (duration: 162.474967ms)","trace[657501724] 'compare' (duration: 53.652345ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:26.52165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.577614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:26.521984Z","caller":"traceutil/trace.go:171","msg":"trace[633348979] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"318.132829ms","start":"2026-02-25T02:15:26.203655Z","end":"2026-02-25T02:15:26.521788Z","steps":["trace[633348979] 'process raft request' (duration: 176.202086ms)","trace[633348979] 'compare' (duration: 141.41699ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:26.522062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:26.203635Z","time spent":"318.397706ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:15:26.562004Z","caller":"traceutil/trace.go:171","msg":"trace[1864668392] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"358.288151ms","start":"2026-02-25T02:15:26.203687Z","end":"2026-02-25T02:15:26.561975Z","steps":["trace[1864668392] 'process raft request' (duration: 357.791838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:26.562142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:26.203665Z","time spent":"358.412125ms","remote":"127.0.0.1:52596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:15:26.562055Z","caller":"traceutil/trace.go:171","msg":"trace[1772665826] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:390; }","duration":"272.824169ms","start":"2026-02-25T02:15:26.289204Z","end":"2026-02-25T02:15:26.562028Z","steps":["trace[1772665826] 'read index received' (duration: 90.731064ms)","trace[1772665826] 'applied index is now lower than readState.Index' (duration: 182.092025ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:26.562192Z","caller":"traceutil/trace.go:171","msg":"trace[1617454300] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"346.474783ms","start":"2026-02-25T02:15:26.21569Z","end":"2026-02-25T02:15:26.562165Z","steps":["trace[1617454300] 'process raft request' (duration: 346.282098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:26.562307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.066376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-25T02:15:26.56235Z","caller":"traceutil/trace.go:171","msg":"trace[265142654] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:380; }","duration":"273.168558ms","start":"2026-02-25T02:15:26.289172Z","end":"2026-02-25T02:15:26.562341Z","steps":["trace[265142654] 'agreement among raft nodes before linearized reading' (duration: 273.048695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:26.562354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:26.215668Z","time spent":"346.593067ms","remote":"127.0.0.1:52596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3979,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:26.562404Z","caller":"traceutil/trace.go:171","msg":"trace[463315917] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"358.61647ms","start":"2026-02-25T02:15:26.203706Z","end":"2026-02-25T02:15:26.562322Z","steps":["trace[463315917] 'process raft request' (duration: 358.155508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:26.562621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:26.203682Z","time spent":"358.825355ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:15:26.821679Z","caller":"traceutil/trace.go:171","msg":"trace[753803] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"254.719382ms","start":"2026-02-25T02:15:26.566914Z","end":"2026-02-25T02:15:26.821633Z","steps":["trace[753803] 'process raft request' (duration: 192.694851ms)","trace[753803] 'compare' (duration: 61.713802ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:26.821717Z","caller":"traceutil/trace.go:171","msg":"trace[1324495778] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"254.512316ms","start":"2026-02-25T02:15:26.567166Z","end":"2026-02-25T02:15:26.821679Z","steps":["trace[1324495778] 'process raft request' (duration: 254.361702ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.821777Z","caller":"traceutil/trace.go:171","msg":"trace[1787205360] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"252.087531ms","start":"2026-02-25T02:15:26.569654Z","end":"2026-02-25T02:15:26.821742Z","steps":["trace[1787205360] 'process raft request' (duration: 251.995638ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.821992Z","caller":"traceutil/trace.go:171","msg":"trace[214331452] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"252.702878ms","start":"2026-02-25T02:15:26.569261Z","end":"2026-02-25T02:15:26.821964Z","steps":["trace[214331452] 'process raft request' (duration: 252.331308ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:27.017196Z","caller":"traceutil/trace.go:171","msg":"trace[875724106] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"112.214552ms","start":"2026-02-25T02:15:26.904939Z","end":"2026-02-25T02:15:27.017153Z","steps":["trace[875724106] 'process raft request' (duration: 95.972225ms)","trace[875724106] 'compare' (duration: 16.008351ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:27.224406Z","caller":"traceutil/trace.go:171","msg":"trace[1866330629] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"192.173197ms","start":"2026-02-25T02:15:27.032208Z","end":"2026-02-25T02:15:27.224382Z","steps":["trace[1866330629] 'process raft request' (duration: 146.230309ms)","trace[1866330629] 'compare' (duration: 45.827165ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:27.543304Z","caller":"traceutil/trace.go:171","msg":"trace[711206591] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"111.775821ms","start":"2026-02-25T02:15:27.431503Z","end":"2026-02-25T02:15:27.543279Z","steps":["trace[711206591] 'process raft request' (duration: 46.359229ms)","trace[711206591] 'compare' (duration: 65.271918ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:30.901759Z","caller":"traceutil/trace.go:171","msg":"trace[819837746] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"264.220521ms","start":"2026-02-25T02:15:30.637514Z","end":"2026-02-25T02:15:30.901734Z","steps":["trace[819837746] 'process raft request' (duration: 264.071397ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:41.333977Z","caller":"traceutil/trace.go:171","msg":"trace[2009866311] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"102.527003ms","start":"2026-02-25T02:15:41.231429Z","end":"2026-02-25T02:15:41.333956Z","steps":["trace[2009866311] 'process raft request' (duration: 102.41453ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:49.624097Z","caller":"traceutil/trace.go:171","msg":"trace[1207715622] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"179.952369ms","start":"2026-02-25T02:15:49.44412Z","end":"2026-02-25T02:15:49.624072Z","steps":["trace[1207715622] 'process raft request' (duration: 80.53365ms)","trace[1207715622] 'compare' (duration: 99.228914ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:49.624143Z","caller":"traceutil/trace.go:171","msg":"trace[982971743] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"178.305999ms","start":"2026-02-25T02:15:49.445817Z","end":"2026-02-25T02:15:49.624123Z","steps":["trace[982971743] 'process raft request' (duration: 178.193926ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:49.624265Z","caller":"traceutil/trace.go:171","msg":"trace[567873686] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"176.415303ms","start":"2026-02-25T02:15:49.447795Z","end":"2026-02-25T02:15:49.62421Z","steps":["trace[567873686] 'process raft request' (duration: 176.264249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:49.624414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.597667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:49.624301Z","caller":"traceutil/trace.go:171","msg":"trace[706915637] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:501; }","duration":"100.499074ms","start":"2026-02-25T02:15:49.523788Z","end":"2026-02-25T02:15:49.624287Z","steps":["trace[706915637] 'read index received' (duration: 850.32µs)","trace[706915637] 'applied index is now lower than readState.Index' (duration: 99.647824ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:49.624477Z","caller":"traceutil/trace.go:171","msg":"trace[828059135] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:484; }","duration":"100.702099ms","start":"2026-02-25T02:15:49.523746Z","end":"2026-02-25T02:15:49.624448Z","steps":["trace[828059135] 'agreement among raft nodes before linearized reading' (duration: 100.605806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:52.42878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.339907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:52.428917Z","caller":"traceutil/trace.go:171","msg":"trace[383610751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"168.517342ms","start":"2026-02-25T02:15:52.260371Z","end":"2026-02-25T02:15:52.428888Z","steps":["trace[383610751] 'range keys from in-memory index tree' (duration: 168.269276ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:54.122468Z","caller":"traceutil/trace.go:171","msg":"trace[1504042253] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"434.369882ms","start":"2026-02-25T02:15:53.688068Z","end":"2026-02-25T02:15:54.122438Z","steps":["trace[1504042253] 'read index received' (duration: 434.132126ms)","trace[1504042253] 'applied index is now lower than readState.Index' (duration: 236.696µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:54.122531Z","caller":"traceutil/trace.go:171","msg":"trace[10283720] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"474.236277ms","start":"2026-02-25T02:15:53.648271Z","end":"2026-02-25T02:15:54.122507Z","steps":["trace[10283720] 'process raft request' (duration: 473.997852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:54.122718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:53.648255Z","time spent":"474.33939ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:54.122752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.682279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T02:15:54.122846Z","caller":"traceutil/trace.go:171","msg":"trace[2134812261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"434.844933ms","start":"2026-02-25T02:15:53.687985Z","end":"2026-02-25T02:15:54.12283Z","steps":["trace[2134812261] 'agreement among raft nodes before linearized reading' (duration: 434.595637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:54.122863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.994764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T02:15:54.122893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:53.687947Z","time spent":"434.933294ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:15:54.122858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.410513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T02:15:54.122974Z","caller":"traceutil/trace.go:171","msg":"trace[1257401249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"413.186758ms","start":"2026-02-25T02:15:53.709756Z","end":"2026-02-25T02:15:54.122943Z","steps":["trace[1257401249] 'agreement among raft nodes before linearized reading' (duration: 412.86439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:54.123058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:53.709724Z","time spent":"413.317961ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T02:15:54.12298Z","caller":"traceutil/trace.go:171","msg":"trace[1869154530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"401.583857ms","start":"2026-02-25T02:15:53.721376Z","end":"2026-02-25T02:15:54.12296Z","steps":["trace[1869154530] 'agreement among raft nodes before linearized reading' (duration: 401.312371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:54.123239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:53.721352Z","time spent":"401.868784ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T02:15:54.416568Z","caller":"traceutil/trace.go:171","msg":"trace[661075439] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"288.752584ms","start":"2026-02-25T02:15:54.127775Z","end":"2026-02-25T02:15:54.416528Z","steps":["trace[661075439] 'process raft request' (duration: 260.888838ms)","trace[661075439] 'compare' (duration: 27.659331ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:54.416703Z","caller":"traceutil/trace.go:171","msg":"trace[1452623537] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"288.911927ms","start":"2026-02-25T02:15:54.127775Z","end":"2026-02-25T02:15:54.416687Z","steps":["trace[1452623537] 'process raft request' (duration: 288.700682ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:54.485208Z","caller":"traceutil/trace.go:171","msg":"trace[1882932163] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:547; }","duration":"234.330095ms","start":"2026-02-25T02:15:54.250839Z","end":"2026-02-25T02:15:54.485169Z","steps":["trace[1882932163] 'read index received' (duration: 137.847398ms)","trace[1882932163] 'applied index is now lower than readState.Index' (duration: 96.480867ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:54.485273Z","caller":"traceutil/trace.go:171","msg":"trace[291124525] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"356.797622ms","start":"2026-02-25T02:15:54.128437Z","end":"2026-02-25T02:15:54.485235Z","steps":["trace[291124525] 'process raft request' (duration: 356.531115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:54.485383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.509059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:54.485435Z","caller":"traceutil/trace.go:171","msg":"trace[99358382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"234.596111ms","start":"2026-02-25T02:15:54.250823Z","end":"2026-02-25T02:15:54.485419Z","steps":["trace[99358382] 'agreement among raft nodes before linearized reading' (duration: 234.480098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:54.48548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:54.128409Z","time spent":"356.946975ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:54.613996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.235662ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T02:15:55.700493Z","caller":"traceutil/trace.go:171","msg":"trace[397628127] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"158.767406ms","start":"2026-02-25T02:15:55.541705Z","end":"2026-02-25T02:15:55.700473Z","steps":["trace[397628127] 'process raft request' (duration: 135.609335ms)","trace[397628127] 'compare' (duration: 22.921455ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:55.700493Z","caller":"traceutil/trace.go:171","msg":"trace[714409108] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"153.928769ms","start":"2026-02-25T02:15:55.546539Z","end":"2026-02-25T02:15:55.700468Z","steps":["trace[714409108] 'process raft request' (duration: 153.866707ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:55.915862Z","caller":"traceutil/trace.go:171","msg":"trace[1849537151] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"133.071824ms","start":"2026-02-25T02:15:55.782767Z","end":"2026-02-25T02:15:55.915839Z","steps":["trace[1849537151] 'process raft request' (duration: 119.237768ms)","trace[1849537151] 'compare' (duration: 13.61806ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:56.12937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.734097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:56.129534Z","caller":"traceutil/trace.go:171","msg":"trace[1650195543] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"176.726131ms","start":"2026-02-25T02:15:55.952772Z","end":"2026-02-25T02:15:56.129498Z","steps":["trace[1650195543] 'process raft request' (duration: 71.761878ms)","trace[1650195543] 'compare' (duration: 104.541322ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:02.429936Z","caller":"traceutil/trace.go:171","msg":"trace[677075511] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"267.310775ms","start":"2026-02-25T02:16:02.162601Z","end":"2026-02-25T02:16:02.429912Z","steps":["trace[677075511] 'read index received' (duration: 267.077549ms)","trace[677075511] 'applied index is now lower than readState.Index' (duration: 232.216µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:02.430054Z","caller":"traceutil/trace.go:171","msg":"trace[1796701231] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"398.487322ms","start":"2026-02-25T02:16:02.031519Z","end":"2026-02-25T02:16:02.430006Z","steps":["trace[1796701231] 'process raft request' (duration: 398.248476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:02.430229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.613683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T02:16:02.430259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:02.031481Z","time spent":"398.677937ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:02.430293Z","caller":"traceutil/trace.go:171","msg":"trace[269232630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:690; }","duration":"267.726436ms","start":"2026-02-25T02:16:02.162549Z","end":"2026-02-25T02:16:02.430276Z","steps":["trace[269232630] 'agreement among raft nodes before linearized reading' (duration: 267.5091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:02.430298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.8542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:02.430581Z","caller":"traceutil/trace.go:171","msg":"trace[1063688594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"181.161238ms","start":"2026-02-25T02:16:02.249343Z","end":"2026-02-25T02:16:02.430504Z","steps":["trace[1063688594] 'agreement among raft nodes before linearized reading' (duration: 180.780649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:03.099429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.824406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:03.099567Z","caller":"traceutil/trace.go:171","msg":"trace[1053103799] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"664.762812ms","start":"2026-02-25T02:16:02.434785Z","end":"2026-02-25T02:16:03.099548Z","steps":["trace[1053103799] 'process raft request' (duration: 356.748821ms)","trace[1053103799] 'compare' (duration: 307.631722ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:03.099624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:02.434754Z","time spent":"664.850694ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:03.748439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.049236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:03.748556Z","caller":"traceutil/trace.go:171","msg":"trace[2049547057] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:712; }","duration":"1.292527888s","start":"2026-02-25T02:16:02.456012Z","end":"2026-02-25T02:16:03.74854Z","steps":["trace[2049547057] 'read index received' (duration: 335.521707ms)","trace[2049547057] 'applied index is now lower than readState.Index' (duration: 957.005271ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:03.748577Z","caller":"traceutil/trace.go:171","msg":"trace[935075659] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"1.302620783s","start":"2026-02-25T02:16:02.44593Z","end":"2026-02-25T02:16:03.748551Z","steps":["trace[935075659] 'process raft request' (duration: 1.173367372s)","trace[935075659] 'compare' (duration: 128.919883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:03.748697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:02.445891Z","time spent":"1.302740035s","remote":"127.0.0.1:52596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:03.748783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284317159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T02:16:03.748826Z","caller":"traceutil/trace.go:171","msg":"trace[866056696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"1.284403481s","start":"2026-02-25T02:16:02.464411Z","end":"2026-02-25T02:16:03.748814Z","steps":["trace[866056696] 'agreement among raft nodes before linearized reading' (duration: 1.284255828s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:03.748885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:02.464393Z","time spent":"1.284483353s","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:16:03.748881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292856656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T02:16:03.748913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225924075s","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-02-25T02:16:03.748881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.147448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:03.748943Z","caller":"traceutil/trace.go:171","msg":"trace[346502516] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:692; }","duration":"1.225988856s","start":"2026-02-25T02:16:02.522945Z","end":"2026-02-25T02:16:03.748934Z","steps":["trace[346502516] 'agreement among raft nodes before linearized reading' (duration: 1.225858003s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:03.748942Z","caller":"traceutil/trace.go:171","msg":"trace[1913624885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:692; }","duration":"1.292985359s","start":"2026-02-25T02:16:02.45594Z","end":"2026-02-25T02:16:03.748925Z","steps":["trace[1913624885] 'agreement among raft nodes before linearized reading' (duration: 1.292702692s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:03.748963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:02.522923Z","time spent":"1.226036127s","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:16:03.748979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:02.455909Z","time spent":"1.293063281s","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:16:03.749177Z","caller":"traceutil/trace.go:171","msg":"trace[517561457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"496.346983ms","start":"2026-02-25T02:16:03.252605Z","end":"2026-02-25T02:16:03.748952Z","steps":["trace[517561457] 'agreement among raft nodes before linearized reading' (duration: 496.176379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:03.749243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:03.252539Z","time spent":"496.69134ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:16:03.873373Z","caller":"traceutil/trace.go:171","msg":"trace[313339608] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"119.162556ms","start":"2026-02-25T02:16:03.754188Z","end":"2026-02-25T02:16:03.873351Z","steps":["trace[313339608] 'process raft request' (duration: 104.901901ms)","trace[313339608] 'compare' (duration: 14.095561ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:03.873642Z","caller":"traceutil/trace.go:171","msg":"trace[1394958526] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"119.338341ms","start":"2026-02-25T02:16:03.754282Z","end":"2026-02-25T02:16:03.87362Z","steps":["trace[1394958526] 'process raft request' (duration: 119.035283ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:03.873804Z","caller":"traceutil/trace.go:171","msg":"trace[1476231037] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"118.306666ms","start":"2026-02-25T02:16:03.75549Z","end":"2026-02-25T02:16:03.873797Z","steps":["trace[1476231037] 'process raft request' (duration: 118.04102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:06.014962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068572102s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T02:16:06.015092Z","caller":"traceutil/trace.go:171","msg":"trace[66398085] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"911.337654ms","start":"2026-02-25T02:16:05.103734Z","end":"2026-02-25T02:16:06.015071Z","steps":["trace[66398085] 'read index received' (duration: 66.911µs)","trace[66398085] 'applied index is now lower than readState.Index' (duration: 911.269113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:06.015321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.578901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T02:16:06.0154Z","caller":"traceutil/trace.go:171","msg":"trace[1306850546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:695; }","duration":"911.723504ms","start":"2026-02-25T02:16:05.103659Z","end":"2026-02-25T02:16:06.015383Z","steps":["trace[1306850546] 'agreement among raft nodes before linearized reading' (duration: 911.496278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:06.015448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:05.103627Z","time spent":"911.812716ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:16:06.015548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.623747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:16:06.015501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.631549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T02:16:06.015608Z","caller":"traceutil/trace.go:171","msg":"trace[954375438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:695; }","duration":"763.865732ms","start":"2026-02-25T02:16:05.251726Z","end":"2026-02-25T02:16:06.015592Z","steps":["trace[954375438] 'agreement among raft nodes before linearized reading' (duration: 763.538764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:06.015626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.338996ms","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-02-25T02:16:06.01566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:05.251693Z","time spent":"763.945974ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:16:06.015655Z","caller":"traceutil/trace.go:171","msg":"trace[2146984312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:695; }","duration":"136.812033ms","start":"2026-02-25T02:16:05.878795Z","end":"2026-02-25T02:16:06.015607Z","steps":["trace[2146984312] 'agreement among raft nodes before linearized reading' (duration: 136.540157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:06.015696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.864839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T02:16:06.015709Z","caller":"traceutil/trace.go:171","msg":"trace[277279881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:695; }","duration":"137.462559ms","start":"2026-02-25T02:16:05.878221Z","end":"2026-02-25T02:16:06.015684Z","steps":["trace[277279881] 'agreement among raft nodes before linearized reading' (duration: 137.201523ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:06.015753Z","caller":"traceutil/trace.go:171","msg":"trace[382524100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:695; }","duration":"137.971372ms","start":"2026-02-25T02:16:05.877767Z","end":"2026-02-25T02:16:06.015739Z","steps":["trace[382524100] 'agreement among raft nodes before linearized reading' (duration: 137.775827ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:08.141928Z","caller":"traceutil/trace.go:171","msg":"trace[542001839] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"111.343947ms","start":"2026-02-25T02:16:08.030544Z","end":"2026-02-25T02:16:08.141888Z","steps":["trace[542001839] 'process raft request' (duration: 40.956852ms)","trace[542001839] 'compare' (duration: 70.104878ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:08.142226Z","caller":"traceutil/trace.go:171","msg":"trace[404689921] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"111.596253ms","start":"2026-02-25T02:16:08.030608Z","end":"2026-02-25T02:16:08.142204Z","steps":["trace[404689921] 'process raft request' (duration: 111.229204ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:08.142383Z","caller":"traceutil/trace.go:171","msg":"trace[301174402] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"109.755508ms","start":"2026-02-25T02:16:08.032609Z","end":"2026-02-25T02:16:08.142364Z","steps":["trace[301174402] 'process raft request' (duration: 109.527393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:09.23199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.454826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:09.232095Z","caller":"traceutil/trace.go:171","msg":"trace[1819239741] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"197.884923ms","start":"2026-02-25T02:16:09.034192Z","end":"2026-02-25T02:16:09.232077Z","steps":["trace[1819239741] 'process raft request' (duration: 42.269644ms)","trace[1819239741] 'compare' (duration: 155.331782ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:09.901728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251988147759291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:16:10.331969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.036073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:10.332087Z","caller":"traceutil/trace.go:171","msg":"trace[364129001] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:739; }","duration":"931.121294ms","start":"2026-02-25T02:16:09.400944Z","end":"2026-02-25T02:16:10.332065Z","steps":["trace[364129001] 'read index received' (duration: 714.821565ms)","trace[364129001] 'applied index is now lower than readState.Index' (duration: 216.297599ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:10.332114Z","caller":"traceutil/trace.go:171","msg":"trace[295284575] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"1.063078011s","start":"2026-02-25T02:16:09.268999Z","end":"2026-02-25T02:16:10.332077Z","steps":["trace[295284575] 'process raft request' (duration: 846.860173ms)","trace[295284575] 'compare' (duration: 215.790027ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:10.332295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:09.268973Z","time spent":"1.063253794s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:16:10.332356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.404141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.41\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T02:16:10.332381Z","caller":"traceutil/trace.go:171","msg":"trace[646288440] range","detail":"{range_begin:/registry/masterleases/199.204.45.41; range_end:; response_count:1; response_revision:717; }","duration":"931.434882ms","start":"2026-02-25T02:16:09.400939Z","end":"2026-02-25T02:16:10.332374Z","steps":["trace[646288440] 'agreement among raft nodes before linearized reading' (duration: 931.184806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:10.332445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:09.400893Z","time spent":"931.499893ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.41\" "} {"level":"warn","ts":"2026-02-25T02:16:10.33254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.985597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xqf78\" ","response":"range_response_count:1 size:17411"} {"level":"info","ts":"2026-02-25T02:16:10.332601Z","caller":"traceutil/trace.go:171","msg":"trace[269092279] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xqf78; range_end:; response_count:1; response_revision:717; }","duration":"916.08968ms","start":"2026-02-25T02:16:09.416496Z","end":"2026-02-25T02:16:10.332586Z","steps":["trace[269092279] 'agreement among raft nodes before linearized reading' (duration: 915.823293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:10.332642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.687618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-25T02:16:10.332669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.81414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T02:16:10.332721Z","caller":"traceutil/trace.go:171","msg":"trace[40887603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:717; }","duration":"185.911653ms","start":"2026-02-25T02:16:10.146795Z","end":"2026-02-25T02:16:10.332706Z","steps":["trace[40887603] 'agreement among raft nodes before linearized reading' (duration: 185.743389ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:10.332723Z","caller":"traceutil/trace.go:171","msg":"trace[557731020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:717; }","duration":"185.806441ms","start":"2026-02-25T02:16:10.146888Z","end":"2026-02-25T02:16:10.332695Z","steps":["trace[557731020] 'agreement among raft nodes before linearized reading' (duration: 185.542574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:10.332731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:09.416473Z","time spent":"916.208222ms","remote":"127.0.0.1:52596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17433,"request content":"key:\"/registry/pods/kube-system/cilium-xqf78\" "} {"level":"warn","ts":"2026-02-25T02:16:10.332878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.960898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T02:16:10.332973Z","caller":"traceutil/trace.go:171","msg":"trace[1596368105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:717; }","duration":"156.078651ms","start":"2026-02-25T02:16:10.176868Z","end":"2026-02-25T02:16:10.332947Z","steps":["trace[1596368105] 'agreement among raft nodes before linearized reading' (duration: 155.528227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:10.333185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.581685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T02:16:10.333846Z","caller":"traceutil/trace.go:171","msg":"trace[688344912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:717; }","duration":"186.20482ms","start":"2026-02-25T02:16:10.147584Z","end":"2026-02-25T02:16:10.333789Z","steps":["trace[688344912] 'agreement among raft nodes before linearized reading' (duration: 184.988061ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:10.671971Z","caller":"traceutil/trace.go:171","msg":"trace[2107683332] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"335.822294ms","start":"2026-02-25T02:16:10.336133Z","end":"2026-02-25T02:16:10.671956Z","steps":["trace[2107683332] 'process raft request' (duration: 335.760093ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:10.672011Z","caller":"traceutil/trace.go:171","msg":"trace[1789692837] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"336.03152ms","start":"2026-02-25T02:16:10.335948Z","end":"2026-02-25T02:16:10.671979Z","steps":["trace[1789692837] 'process raft request' (duration: 335.841005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:10.672185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.335925Z","time spent":"336.159503ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:10.672228Z","caller":"traceutil/trace.go:171","msg":"trace[741687944] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"335.61664ms","start":"2026-02-25T02:16:10.336604Z","end":"2026-02-25T02:16:10.67222Z","steps":["trace[741687944] 'process raft request' (duration: 335.310652ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:10.672272Z","caller":"traceutil/trace.go:171","msg":"trace[617639607] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"334.901443ms","start":"2026-02-25T02:16:10.337354Z","end":"2026-02-25T02:16:10.672256Z","steps":["trace[617639607] 'process raft request' (duration: 334.77903ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:10.672337Z","caller":"traceutil/trace.go:171","msg":"trace[623463906] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"329.331397ms","start":"2026-02-25T02:16:10.342987Z","end":"2026-02-25T02:16:10.672319Z","steps":["trace[623463906] 'process raft request' (duration: 329.184464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:10.672359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.337349Z","time spent":"334.964624ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:10.672278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.336598Z","time spent":"335.654161ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:10.672456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.342974Z","time spent":"329.41381ms","remote":"127.0.0.1:52596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17558,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:10.672685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.336113Z","time spent":"335.922857ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:10.724582Z","caller":"traceutil/trace.go:171","msg":"trace[357696340] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"372.306428ms","start":"2026-02-25T02:16:10.352255Z","end":"2026-02-25T02:16:10.724562Z","steps":["trace[357696340] 'process raft request' (duration: 372.190576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:10.724723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:10.352237Z","time spent":"372.411111ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:11.002405Z","caller":"traceutil/trace.go:171","msg":"trace[463217425] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"232.474531ms","start":"2026-02-25T02:16:10.769895Z","end":"2026-02-25T02:16:11.00237Z","steps":["trace[463217425] 'process raft request' (duration: 232.271956ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:11.15968Z","caller":"traceutil/trace.go:171","msg":"trace[1504135061] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"136.894686ms","start":"2026-02-25T02:16:11.022755Z","end":"2026-02-25T02:16:11.15965Z","steps":["trace[1504135061] 'process raft request' (duration: 100.489884ms)","trace[1504135061] 'compare' (duration: 36.205407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:11.650245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.497254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:11.650435Z","caller":"traceutil/trace.go:171","msg":"trace[1387034674] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"217.629372ms","start":"2026-02-25T02:16:11.432788Z","end":"2026-02-25T02:16:11.650417Z","steps":["trace[1387034674] 'process raft request' (duration: 217.5575ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:11.650424Z","caller":"traceutil/trace.go:171","msg":"trace[2079970012] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"396.779371ms","start":"2026-02-25T02:16:11.25359Z","end":"2026-02-25T02:16:11.650369Z","steps":["trace[2079970012] 'process raft request' (duration: 180.035651ms)","trace[2079970012] 'compare' (duration: 216.365981ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:11.650505Z","caller":"traceutil/trace.go:171","msg":"trace[46473560] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"283.796814ms","start":"2026-02-25T02:16:11.366679Z","end":"2026-02-25T02:16:11.650476Z","steps":["trace[46473560] 'read index received' (duration: 66.941412ms)","trace[46473560] 'applied index is now lower than readState.Index' (duration: 216.853492ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:11.650574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:11.253563Z","time spent":"396.952405ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:11.651006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.304837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:11.651108Z","caller":"traceutil/trace.go:171","msg":"trace[1714125774] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:728; }","duration":"284.45367ms","start":"2026-02-25T02:16:11.366634Z","end":"2026-02-25T02:16:11.651088Z","steps":["trace[1714125774] 'agreement among raft nodes before linearized reading' (duration: 284.264946ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:11.720978Z","caller":"traceutil/trace.go:171","msg":"trace[225520166] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"152.203516ms","start":"2026-02-25T02:16:11.568752Z","end":"2026-02-25T02:16:11.720956Z","steps":["trace[225520166] 'process raft request' (duration: 152.078293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:11.721071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.068496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xqf78\" ","response":"range_response_count:1 size:17573"} {"level":"info","ts":"2026-02-25T02:16:11.721129Z","caller":"traceutil/trace.go:171","msg":"trace[927399214] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xqf78; range_end:; response_count:1; response_revision:729; }","duration":"290.161958ms","start":"2026-02-25T02:16:11.430953Z","end":"2026-02-25T02:16:11.721115Z","steps":["trace[927399214] 'agreement among raft nodes before linearized reading' (duration: 289.970094ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:11.843092Z","caller":"traceutil/trace.go:171","msg":"trace[1363190978] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"118.550071ms","start":"2026-02-25T02:16:11.724519Z","end":"2026-02-25T02:16:11.843069Z","steps":["trace[1363190978] 'process raft request' (duration: 118.393418ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:11.89375Z","caller":"traceutil/trace.go:171","msg":"trace[1802072091] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"164.398482ms","start":"2026-02-25T02:16:11.729332Z","end":"2026-02-25T02:16:11.89373Z","steps":["trace[1802072091] 'process raft request' (duration: 164.125825ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:11.893888Z","caller":"traceutil/trace.go:171","msg":"trace[354204163] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"159.629116ms","start":"2026-02-25T02:16:11.734252Z","end":"2026-02-25T02:16:11.893881Z","steps":["trace[354204163] 'process raft request' (duration: 159.398571ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:11.893771Z","caller":"traceutil/trace.go:171","msg":"trace[1311415573] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"125.51545ms","start":"2026-02-25T02:16:11.768236Z","end":"2026-02-25T02:16:11.893751Z","steps":["trace[1311415573] 'process raft request' (duration: 125.473449ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:12.015443Z","caller":"traceutil/trace.go:171","msg":"trace[1272616183] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"117.26192ms","start":"2026-02-25T02:16:11.898144Z","end":"2026-02-25T02:16:12.015406Z","steps":["trace[1272616183] 'process raft request' (duration: 63.627751ms)","trace[1272616183] 'compare' (duration: 53.488436ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:12.02381Z","caller":"traceutil/trace.go:171","msg":"trace[394376499] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"125.630963ms","start":"2026-02-25T02:16:11.898163Z","end":"2026-02-25T02:16:12.023794Z","steps":["trace[394376499] 'process raft request' (duration: 125.410518ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:12.023863Z","caller":"traceutil/trace.go:171","msg":"trace[1618118244] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"120.97449ms","start":"2026-02-25T02:16:11.902862Z","end":"2026-02-25T02:16:12.023836Z","steps":["trace[1618118244] 'process raft request' (duration: 120.919239ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:12.023955Z","caller":"traceutil/trace.go:171","msg":"trace[290075838] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"124.362093ms","start":"2026-02-25T02:16:11.899576Z","end":"2026-02-25T02:16:12.023938Z","steps":["trace[290075838] 'process raft request' (duration: 124.148107ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:12.380234Z","caller":"traceutil/trace.go:171","msg":"trace[569763956] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"411.759423ms","start":"2026-02-25T02:16:11.968452Z","end":"2026-02-25T02:16:12.380211Z","steps":["trace[569763956] 'process raft request' (duration: 411.577308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:12.380396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:11.968432Z","time spent":"411.883716ms","remote":"127.0.0.1:52452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:16:12.6535Z","caller":"traceutil/trace.go:171","msg":"trace[2081899317] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"623.917341ms","start":"2026-02-25T02:16:12.029558Z","end":"2026-02-25T02:16:12.653475Z","steps":["trace[2081899317] 'process raft request' (duration: 623.705306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:12.653664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:12.029518Z","time spent":"624.057565ms","remote":"127.0.0.1:52910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:12.653775Z","caller":"traceutil/trace.go:171","msg":"trace[283267172] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"483.748606ms","start":"2026-02-25T02:16:12.17001Z","end":"2026-02-25T02:16:12.653759Z","steps":["trace[283267172] 'process raft request' (duration: 483.644624ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:12.653777Z","caller":"traceutil/trace.go:171","msg":"trace[166024202] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"623.157553ms","start":"2026-02-25T02:16:12.030592Z","end":"2026-02-25T02:16:12.653749Z","steps":["trace[166024202] 'process raft request' (duration: 622.830545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:12.653933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:12.03058Z","time spent":"623.270535ms","remote":"127.0.0.1:52452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4318,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:12.653913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:12.169947Z","time spent":"483.89631ms","remote":"127.0.0.1:52452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:16:12.654035Z","caller":"traceutil/trace.go:171","msg":"trace[365667829] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:761; }","duration":"406.091056ms","start":"2026-02-25T02:16:12.247927Z","end":"2026-02-25T02:16:12.654018Z","steps":["trace[365667829] 'read index received' (duration: 132.136101ms)","trace[365667829] 'applied index is now lower than readState.Index' (duration: 273.953815ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:12.654058Z","caller":"traceutil/trace.go:171","msg":"trace[1991307702] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"423.808964ms","start":"2026-02-25T02:16:12.230236Z","end":"2026-02-25T02:16:12.654045Z","steps":["trace[1991307702] 'process raft request' (duration: 423.498557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:12.654113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:12.230191Z","time spent":"423.896037ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:16:12.65411Z","caller":"traceutil/trace.go:171","msg":"trace[2028824132] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"283.971888ms","start":"2026-02-25T02:16:12.370115Z","end":"2026-02-25T02:16:12.654087Z","steps":["trace[2028824132] 'process raft request' (duration: 283.845745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:12.65414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.212229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:12.654209Z","caller":"traceutil/trace.go:171","msg":"trace[1856565622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"406.336112ms","start":"2026-02-25T02:16:12.247862Z","end":"2026-02-25T02:16:12.654199Z","steps":["trace[1856565622] 'agreement among raft nodes before linearized reading' (duration: 406.219439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:12.65424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:12.247837Z","time spent":"406.396564ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:16:12.714879Z","caller":"traceutil/trace.go:171","msg":"trace[543130567] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"330.105796ms","start":"2026-02-25T02:16:12.384751Z","end":"2026-02-25T02:16:12.714857Z","steps":["trace[543130567] 'process raft request' (duration: 329.947062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:12.715014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:12.384708Z","time spent":"330.235559ms","remote":"127.0.0.1:52452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:12.715179Z","caller":"traceutil/trace.go:171","msg":"trace[8335389] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"135.815239ms","start":"2026-02-25T02:16:12.579342Z","end":"2026-02-25T02:16:12.715157Z","steps":["trace[8335389] 'process raft request' (duration: 135.660416ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:12.715253Z","caller":"traceutil/trace.go:171","msg":"trace[381132752] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"142.171013ms","start":"2026-02-25T02:16:12.573072Z","end":"2026-02-25T02:16:12.715243Z","steps":["trace[381132752] 'process raft request' (duration: 141.739483ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:12.971387Z","caller":"traceutil/trace.go:171","msg":"trace[1208530524] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"121.464872ms","start":"2026-02-25T02:16:12.849896Z","end":"2026-02-25T02:16:12.971361Z","steps":["trace[1208530524] 'process raft request' (duration: 71.081182ms)","trace[1208530524] 'compare' (duration: 50.254247ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:12.981966Z","caller":"traceutil/trace.go:171","msg":"trace[17941321] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"130.820539ms","start":"2026-02-25T02:16:12.851136Z","end":"2026-02-25T02:16:12.981957Z","steps":["trace[17941321] 'process raft request' (duration: 130.759547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:14.185367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.523764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T02:16:14.185513Z","caller":"traceutil/trace.go:171","msg":"trace[197412662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:776; }","duration":"530.759171ms","start":"2026-02-25T02:16:13.654728Z","end":"2026-02-25T02:16:14.185487Z","steps":["trace[197412662] 'range keys from in-memory index tree' (duration: 530.269888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:14.185572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:13.654696Z","time spent":"530.863684ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T02:16:14.185711Z","caller":"traceutil/trace.go:171","msg":"trace[1667163383] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"417.9866ms","start":"2026-02-25T02:16:13.767695Z","end":"2026-02-25T02:16:14.185682Z","steps":["trace[1667163383] 'read index received' (duration: 385.445269ms)","trace[1667163383] 'applied index is now lower than readState.Index' (duration: 32.539971ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:14.185915Z","caller":"traceutil/trace.go:171","msg":"trace[1123016164] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"476.525918ms","start":"2026-02-25T02:16:13.709356Z","end":"2026-02-25T02:16:14.185882Z","steps":["trace[1123016164] 'process raft request' (duration: 444.000428ms)","trace[1123016164] 'compare' (duration: 32.015377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:14.186106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:13.709313Z","time spent":"476.694313ms","remote":"127.0.0.1:52452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:14.186153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.453792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-25T02:16:14.186242Z","caller":"traceutil/trace.go:171","msg":"trace[802680451] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:777; }","duration":"418.601756ms","start":"2026-02-25T02:16:13.767618Z","end":"2026-02-25T02:16:14.186219Z","steps":["trace[802680451] 'agreement among raft nodes before linearized reading' (duration: 418.261877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:14.186285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:13.767589Z","time spent":"418.687368ms","remote":"127.0.0.1:52452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-25T02:16:14.186514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.875768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-25T02:16:14.186608Z","caller":"traceutil/trace.go:171","msg":"trace[1160717741] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:777; }","duration":"207.024012ms","start":"2026-02-25T02:16:13.97956Z","end":"2026-02-25T02:16:14.186584Z","steps":["trace[1160717741] 'agreement among raft nodes before linearized reading' (duration: 206.614231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:29.123907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.831879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:29.124045Z","caller":"traceutil/trace.go:171","msg":"trace[434854866] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"486.58739ms","start":"2026-02-25T02:16:28.637436Z","end":"2026-02-25T02:16:29.124024Z","steps":["trace[434854866] 'process raft request' (duration: 208.527645ms)","trace[434854866] 'compare' (duration: 277.629684ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:29.124125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:28.637399Z","time spent":"486.678622ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:29.155547Z","caller":"traceutil/trace.go:171","msg":"trace[548516720] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:986; }","duration":"456.34722ms","start":"2026-02-25T02:16:28.699164Z","end":"2026-02-25T02:16:29.155511Z","steps":["trace[548516720] 'read index received' (duration: 146.819493ms)","trace[548516720] 'applied index is now lower than readState.Index' (duration: 309.525797ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:29.155617Z","caller":"traceutil/trace.go:171","msg":"trace[439200041] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"449.021756ms","start":"2026-02-25T02:16:28.706582Z","end":"2026-02-25T02:16:29.155604Z","steps":["trace[439200041] 'process raft request' (duration: 448.844371ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:29.155676Z","caller":"traceutil/trace.go:171","msg":"trace[1480515400] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"517.21195ms","start":"2026-02-25T02:16:28.638427Z","end":"2026-02-25T02:16:29.155639Z","steps":["trace[1480515400] 'process raft request' (duration: 516.856751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:29.155803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.710484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T02:16:29.15582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.594637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:16:29.155818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.869674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T02:16:29.155838Z","caller":"traceutil/trace.go:171","msg":"trace[1932241710] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"266.787336ms","start":"2026-02-25T02:16:28.889043Z","end":"2026-02-25T02:16:29.15583Z","steps":["trace[1932241710] 'agreement among raft nodes before linearized reading' (duration: 266.647592ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:29.155862Z","caller":"traceutil/trace.go:171","msg":"trace[2105130667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"253.941957ms","start":"2026-02-25T02:16:28.901909Z","end":"2026-02-25T02:16:29.155851Z","steps":["trace[2105130667] 'agreement among raft nodes before linearized reading' (duration: 253.784793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:29.155879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:28.638411Z","time spent":"517.359484ms","remote":"127.0.0.1:52692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1613,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:29.155875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.800661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T02:16:29.155944Z","caller":"traceutil/trace.go:171","msg":"trace[1983708483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"248.902083ms","start":"2026-02-25T02:16:28.907033Z","end":"2026-02-25T02:16:29.155935Z","steps":["trace[1983708483] 'agreement among raft nodes before linearized reading' (duration: 248.692627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:29.15604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:28.706565Z","time spent":"449.416386ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:29.155968Z","caller":"traceutil/trace.go:171","msg":"trace[451333052] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:960; }","duration":"456.745141ms","start":"2026-02-25T02:16:28.6991Z","end":"2026-02-25T02:16:29.155845Z","steps":["trace[451333052] 'agreement among raft nodes before linearized reading' (duration: 456.554775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:29.156089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:28.699066Z","time spent":"457.008588ms","remote":"127.0.0.1:52874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:16:29.156077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.84957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T02:16:29.15621Z","caller":"traceutil/trace.go:171","msg":"trace[2066328416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:960; }","duration":"258.038355ms","start":"2026-02-25T02:16:28.898136Z","end":"2026-02-25T02:16:29.156174Z","steps":["trace[2066328416] 'agreement among raft nodes before linearized reading' (duration: 257.644335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:29.757787Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251988147759752,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:16:30.258345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251988147759752,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:16:30.683031Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.51571849s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:16:30.683527Z","caller":"traceutil/trace.go:171","msg":"trace[1987297834] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"1.52214791s","start":"2026-02-25T02:16:29.161354Z","end":"2026-02-25T02:16:30.683502Z","steps":["trace[1987297834] 'process raft request' (duration: 1.521914844s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:30.683679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:29.161335Z","time spent":"1.522249592s","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:30.683804Z","caller":"traceutil/trace.go:171","msg":"trace[367841688] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"1.522356505s","start":"2026-02-25T02:16:29.161426Z","end":"2026-02-25T02:16:30.683782Z","steps":["trace[367841688] 'process raft request' (duration: 1.522002206s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:30.683901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:29.161397Z","time spent":"1.522446748s","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:30.684118Z","caller":"traceutil/trace.go:171","msg":"trace[1667495437] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:990; }","duration":"1.426802618s","start":"2026-02-25T02:16:29.257278Z","end":"2026-02-25T02:16:30.684081Z","steps":["trace[1667495437] 'read index received' (duration: 1.425935775s)","trace[1667495437] 'applied index is now lower than readState.Index' (duration: 865.253µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:30.684241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426964242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:30.684304Z","caller":"traceutil/trace.go:171","msg":"trace[1464920098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"1.427061675s","start":"2026-02-25T02:16:29.257227Z","end":"2026-02-25T02:16:30.684288Z","steps":["trace[1464920098] 'agreement among raft nodes before linearized reading' (duration: 1.426974872s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:30.684356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:29.257209Z","time spent":"1.427134776s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:16:30.684183Z","caller":"traceutil/trace.go:171","msg":"trace[136933148] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"1.522019947s","start":"2026-02-25T02:16:29.162148Z","end":"2026-02-25T02:16:30.684168Z","steps":["trace[136933148] 'process raft request' (duration: 1.521584895s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:30.684586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:29.16214Z","time spent":"1.522343445s","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:30.684711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.629616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:16:30.685292Z","caller":"traceutil/trace.go:171","msg":"trace[128859982] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:964; }","duration":"159.247752ms","start":"2026-02-25T02:16:30.52599Z","end":"2026-02-25T02:16:30.685238Z","steps":["trace[128859982] 'agreement among raft nodes before linearized reading' (duration: 158.559454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:30.684785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.678625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T02:16:30.685549Z","caller":"traceutil/trace.go:171","msg":"trace[483341201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:964; }","duration":"410.466757ms","start":"2026-02-25T02:16:30.275063Z","end":"2026-02-25T02:16:30.685529Z","steps":["trace[483341201] 'agreement among raft nodes before linearized reading' (duration: 409.622294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:30.685639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:30.275046Z","time spent":"410.5718ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:16:30.684666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281563076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.41\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T02:16:30.685761Z","caller":"traceutil/trace.go:171","msg":"trace[1975723272] range","detail":"{range_begin:/registry/masterleases/199.204.45.41; range_end:; response_count:1; response_revision:964; }","duration":"1.282706306s","start":"2026-02-25T02:16:29.403038Z","end":"2026-02-25T02:16:30.685744Z","steps":["trace[1975723272] 'agreement among raft nodes before linearized reading' (duration: 1.281495354s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:30.685828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:29.403012Z","time spent":"1.282797649s","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.41\" "} {"level":"warn","ts":"2026-02-25T02:16:31.05069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.964079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:16:31.05081Z","caller":"traceutil/trace.go:171","msg":"trace[881643474] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:967; }","duration":"110.133253ms","start":"2026-02-25T02:16:30.940642Z","end":"2026-02-25T02:16:31.050775Z","steps":["trace[881643474] 'count revisions from in-memory index tree' (duration: 109.877716ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:31.845673Z","caller":"traceutil/trace.go:171","msg":"trace[1411499187] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"213.605719ms","start":"2026-02-25T02:16:31.632039Z","end":"2026-02-25T02:16:31.845645Z","steps":["trace[1411499187] 'process raft request' (duration: 213.418014ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:32.021353Z","caller":"traceutil/trace.go:171","msg":"trace[2030718556] transaction","detail":"{read_only:false; number_of_response:1; response_revision:972; }","duration":"173.484269ms","start":"2026-02-25T02:16:31.847834Z","end":"2026-02-25T02:16:32.021318Z","steps":["trace[2030718556] 'process raft request' (duration: 102.505072ms)","trace[2030718556] 'compare' (duration: 70.734141ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:38.84891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.899215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T02:16:38.848972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.500067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T02:16:38.84901Z","caller":"traceutil/trace.go:171","msg":"trace[579979226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:995; }","duration":"103.050709ms","start":"2026-02-25T02:16:38.745936Z","end":"2026-02-25T02:16:38.848986Z","steps":["trace[579979226] 'range keys from in-memory index tree' (duration: 102.693539ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:38.849058Z","caller":"traceutil/trace.go:171","msg":"trace[947491047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"104.64491ms","start":"2026-02-25T02:16:38.744391Z","end":"2026-02-25T02:16:38.849036Z","steps":["trace[947491047] 'range keys from in-memory index tree' (duration: 104.296741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:38.849057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.619013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T02:16:38.849114Z","caller":"traceutil/trace.go:171","msg":"trace[1489628163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"118.729476ms","start":"2026-02-25T02:16:38.730371Z","end":"2026-02-25T02:16:38.849101Z","steps":["trace[1489628163] 'range keys from in-memory index tree' (duration: 118.419198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:38.849269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.88257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T02:16:38.84933Z","caller":"traceutil/trace.go:171","msg":"trace[1798800038] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:995; }","duration":"104.178829ms","start":"2026-02-25T02:16:38.745136Z","end":"2026-02-25T02:16:38.849315Z","steps":["trace[1798800038] 'range keys from in-memory index tree' (duration: 103.703055ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:42.259406Z","caller":"traceutil/trace.go:171","msg":"trace[1125065284] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"196.356785ms","start":"2026-02-25T02:16:42.063016Z","end":"2026-02-25T02:16:42.259373Z","steps":["trace[1125065284] 'process raft request' (duration: 196.14658ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:43.445475Z","caller":"traceutil/trace.go:171","msg":"trace[1233009121] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"139.6445ms","start":"2026-02-25T02:16:43.305807Z","end":"2026-02-25T02:16:43.445451Z","steps":["trace[1233009121] 'process raft request' (duration: 94.076176ms)","trace[1233009121] 'compare' (duration: 45.450321ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:43.498819Z","caller":"traceutil/trace.go:171","msg":"trace[944962408] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"192.737281ms","start":"2026-02-25T02:16:43.306042Z","end":"2026-02-25T02:16:43.498779Z","steps":["trace[944962408] 'process raft request' (duration: 192.614748ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:43.498839Z","caller":"traceutil/trace.go:171","msg":"trace[1445806194] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"192.33884ms","start":"2026-02-25T02:16:43.306466Z","end":"2026-02-25T02:16:43.498805Z","steps":["trace[1445806194] 'process raft request' (duration: 192.222487ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:43.498862Z","caller":"traceutil/trace.go:171","msg":"trace[1513854742] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1077; }","duration":"117.029922ms","start":"2026-02-25T02:16:43.381808Z","end":"2026-02-25T02:16:43.498838Z","steps":["trace[1513854742] 'read index received' (duration: 18.08671ms)","trace[1513854742] 'applied index is now lower than readState.Index' (duration: 98.942412ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:43.499029Z","caller":"traceutil/trace.go:171","msg":"trace[1713368870] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"187.535806ms","start":"2026-02-25T02:16:43.31148Z","end":"2026-02-25T02:16:43.499016Z","steps":["trace[1713368870] 'process raft request' (duration: 187.290409ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:43.499033Z","caller":"traceutil/trace.go:171","msg":"trace[1383714208] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"192.495124ms","start":"2026-02-25T02:16:43.306504Z","end":"2026-02-25T02:16:43.498999Z","steps":["trace[1383714208] 'process raft request' (duration: 192.212547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:43.499054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.232168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T02:16:43.49906Z","caller":"traceutil/trace.go:171","msg":"trace[1137670901] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"193.10252ms","start":"2026-02-25T02:16:43.30594Z","end":"2026-02-25T02:16:43.499042Z","steps":["trace[1137670901] 'process raft request' (duration: 192.594936ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:43.499099Z","caller":"traceutil/trace.go:171","msg":"trace[1419055388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1050; }","duration":"117.31951ms","start":"2026-02-25T02:16:43.381768Z","end":"2026-02-25T02:16:43.499088Z","steps":["trace[1419055388] 'agreement among raft nodes before linearized reading' (duration: 117.154655ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:43.498977Z","caller":"traceutil/trace.go:171","msg":"trace[429943988] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"191.766355ms","start":"2026-02-25T02:16:43.307099Z","end":"2026-02-25T02:16:43.498865Z","steps":["trace[429943988] 'process raft request' (duration: 191.642062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:48.406962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.692041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:48.407084Z","caller":"traceutil/trace.go:171","msg":"trace[1765405207] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"155.916234ms","start":"2026-02-25T02:16:48.251151Z","end":"2026-02-25T02:16:48.407067Z","steps":["trace[1765405207] 'read index received' (duration: 27.886625ms)","trace[1765405207] 'applied index is now lower than readState.Index' (duration: 128.028519ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:48.407144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.004996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:48.407143Z","caller":"traceutil/trace.go:171","msg":"trace[667389430] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"168.802049ms","start":"2026-02-25T02:16:48.238315Z","end":"2026-02-25T02:16:48.407117Z","steps":["trace[667389430] 'process raft request' (duration: 40.859233ms)","trace[667389430] 'compare' (duration: 127.518875ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:48.407177Z","caller":"traceutil/trace.go:171","msg":"trace[357257608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"156.078058ms","start":"2026-02-25T02:16:48.25109Z","end":"2026-02-25T02:16:48.407168Z","steps":["trace[357257608] 'agreement among raft nodes before linearized reading' (duration: 156.023326ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:51.951858Z","caller":"traceutil/trace.go:171","msg":"trace[1004870062] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"149.468186ms","start":"2026-02-25T02:16:51.802364Z","end":"2026-02-25T02:16:51.951832Z","steps":["trace[1004870062] 'read index received' (duration: 149.319472ms)","trace[1004870062] 'applied index is now lower than readState.Index' (duration: 147.654µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:51.951924Z","caller":"traceutil/trace.go:171","msg":"trace[1733496801] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"244.939288ms","start":"2026-02-25T02:16:51.706956Z","end":"2026-02-25T02:16:51.951895Z","steps":["trace[1733496801] 'process raft request' (duration: 244.733142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:51.952043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.64955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:16:51.952132Z","caller":"traceutil/trace.go:171","msg":"trace[960025149] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1115; }","duration":"149.790534ms","start":"2026-02-25T02:16:51.802322Z","end":"2026-02-25T02:16:51.952112Z","steps":["trace[960025149] 'agreement among raft nodes before linearized reading' (duration: 149.61997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:03.307226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.905369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:17:03.307536Z","caller":"traceutil/trace.go:171","msg":"trace[1472870128] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"259.325562ms","start":"2026-02-25T02:17:03.048168Z","end":"2026-02-25T02:17:03.307493Z","steps":["trace[1472870128] 'process raft request' (duration: 47.042043ms)","trace[1472870128] 'compare' (duration: 211.688843ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:17:03.307561Z","caller":"traceutil/trace.go:171","msg":"trace[378841621] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1347; }","duration":"179.964459ms","start":"2026-02-25T02:17:03.127559Z","end":"2026-02-25T02:17:03.307523Z","steps":["trace[378841621] 'read index received' (duration: 79.655731ms)","trace[378841621] 'applied index is now lower than readState.Index' (duration: 100.304207ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:17:03.307794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.229656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-25T02:17:03.307857Z","caller":"traceutil/trace.go:171","msg":"trace[93925791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1308; }","duration":"180.39132ms","start":"2026-02-25T02:17:03.12745Z","end":"2026-02-25T02:17:03.307842Z","steps":["trace[93925791] 'agreement among raft nodes before linearized reading' (duration: 180.159794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:03.308004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.315918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T02:17:03.30805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.727767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T02:17:03.308084Z","caller":"traceutil/trace.go:171","msg":"trace[1440831053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1308; }","duration":"175.444302ms","start":"2026-02-25T02:17:03.132619Z","end":"2026-02-25T02:17:03.308064Z","steps":["trace[1440831053] 'agreement among raft nodes before linearized reading' (duration: 175.189295ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:17:03.308093Z","caller":"traceutil/trace.go:171","msg":"trace[1586635407] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1308; }","duration":"163.829529ms","start":"2026-02-25T02:17:03.144249Z","end":"2026-02-25T02:17:03.308079Z","steps":["trace[1586635407] 'agreement among raft nodes before linearized reading' (duration: 163.667335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:03.308152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.42864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T02:17:03.308215Z","caller":"traceutil/trace.go:171","msg":"trace[1725322792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1308; }","duration":"175.539984ms","start":"2026-02-25T02:17:03.13266Z","end":"2026-02-25T02:17:03.3082Z","steps":["trace[1725322792] 'agreement among raft nodes before linearized reading' (duration: 175.38558ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:17:03.308031Z","caller":"traceutil/trace.go:171","msg":"trace[1405281115] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"186.442988ms","start":"2026-02-25T02:17:03.121154Z","end":"2026-02-25T02:17:03.307597Z","steps":["trace[1405281115] 'process raft request' (duration: 186.244112ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:17:30.131364Z","caller":"traceutil/trace.go:171","msg":"trace[1867586857] linearizableReadLoop","detail":"{readStateIndex:1646; appliedIndex:1645; }","duration":"101.58835ms","start":"2026-02-25T02:17:30.029744Z","end":"2026-02-25T02:17:30.131332Z","steps":["trace[1867586857] 'read index received' (duration: 101.384305ms)","trace[1867586857] 'applied index is now lower than readState.Index' (duration: 202.395µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:17:30.131416Z","caller":"traceutil/trace.go:171","msg":"trace[1482666815] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"326.245182ms","start":"2026-02-25T02:17:29.80513Z","end":"2026-02-25T02:17:30.131375Z","steps":["trace[1482666815] 'process raft request' (duration: 325.982345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:30.131635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:29.805092Z","time spent":"326.446517ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:17:30.131735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.959991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-02-25T02:17:30.131829Z","caller":"traceutil/trace.go:171","msg":"trace[548654655] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1578; }","duration":"102.099724ms","start":"2026-02-25T02:17:30.029709Z","end":"2026-02-25T02:17:30.131808Z","steps":["trace[548654655] 'agreement among raft nodes before linearized reading' (duration: 101.803316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:30.959488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.663184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:17:30.959619Z","caller":"traceutil/trace.go:171","msg":"trace[1330310505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1578; }","duration":"708.884469ms","start":"2026-02-25T02:17:30.250705Z","end":"2026-02-25T02:17:30.959589Z","steps":["trace[1330310505] 'range keys from in-memory index tree' (duration: 708.525451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:30.959675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:30.250674Z","time spent":"708.986462ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:17:30.960585Z","caller":"traceutil/trace.go:171","msg":"trace[757687956] transaction","detail":"{read_only:false; response_revision:1579; number_of_response:1; }","duration":"314.174708ms","start":"2026-02-25T02:17:30.646367Z","end":"2026-02-25T02:17:30.960542Z","steps":["trace[757687956] 'process raft request' (duration: 278.523251ms)","trace[757687956] 'compare' (duration: 35.270717ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:17:30.960839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:30.646344Z","time spent":"314.354072ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:25:15.585501Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2152} {"level":"info","ts":"2026-02-25T02:25:15.650648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2152,"took":"64.070075ms","hash":2555332930,"current-db-size-bytes":14045184,"current-db-size":"14 MB","current-db-size-in-use-bytes":4300800,"current-db-size-in-use":"4.3 MB"} {"level":"info","ts":"2026-02-25T02:25:15.650729Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2555332930,"revision":2152,"compact-revision":-1} {"level":"info","ts":"2026-02-25T02:30:15.592448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3083} {"level":"info","ts":"2026-02-25T02:30:15.612632Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3083,"took":"19.505153ms","hash":2557458254,"current-db-size-bytes":14045184,"current-db-size":"14 MB","current-db-size-in-use-bytes":4689920,"current-db-size-in-use":"4.7 MB"} {"level":"info","ts":"2026-02-25T02:30:15.612703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2557458254,"revision":3083,"compact-revision":2152}