{"level":"warn","ts":"2026-02-26T01:42:13.67141Z","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-26T01:42:13.671517Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-26T01:42:13.671592Z","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-26T01:42:13.671607Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-02-26T01:42:13.671627Z","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-26T01:42:13.672306Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-02-26T01:42:13.672467Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-26T01:42:13.677834Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.724614ms"} {"level":"info","ts":"2026-02-26T01:42:13.6862Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-02-26T01:42:13.686362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T01:42:13.686411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-02-26T01:42:13.686436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T01:42:13.686463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-02-26T01:42:13.686532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-02-26T01:42:13.69525Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T01:42:13.698853Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T01:42:13.70127Z","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-26T01:42:13.704919Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T01:42:13.705257Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T01:42:13.705411Z","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-26T01:42:13.70551Z","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-26T01:42:13.705524Z","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-26T01:42:13.707093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-02-26T01:42:13.707491Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-02-26T01:42:13.711448Z","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-26T01:42:13.711604Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-02-26T01:42:13.711685Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-02-26T01:42:13.71188Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T01:42:13.711958Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T01:42:14.387687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T01:42:14.38776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T01:42:14.387784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-02-26T01:42:14.387805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-02-26T01:42:14.387816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-02-26T01:42:14.387832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-02-26T01:42:14.387846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-02-26T01:42:14.389379Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:42:14.390552Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:42:14.390544Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T01:42:14.390621Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:42:14.391014Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:42:14.391196Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:42:14.391337Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:42:14.392311Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T01:42:14.392342Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T01:42:14.392646Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-02-26T01:42:14.394094Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-26T01:42:15.945984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.08773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T01:42:15.946115Z","caller":"traceutil/trace.go:171","msg":"trace[568951206] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:12; }","duration":"139.252743ms","start":"2026-02-26T01:42:15.806837Z","end":"2026-02-26T01:42:15.94609Z","steps":["trace[568951206] 'agreement among raft nodes before linearized reading' (duration: 139.058269ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:15.946321Z","caller":"traceutil/trace.go:171","msg":"trace[1107588508] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"116.248053ms","start":"2026-02-26T01:42:15.83006Z","end":"2026-02-26T01:42:15.946308Z","steps":["trace[1107588508] 'process raft request' (duration: 115.795494ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:15.946439Z","caller":"traceutil/trace.go:171","msg":"trace[764476776] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"129.480758ms","start":"2026-02-26T01:42:15.816672Z","end":"2026-02-26T01:42:15.946152Z","steps":["trace[764476776] 'process raft request' (duration: 113.484428ms)","trace[764476776] 'compare' (duration: 15.463619ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:15.946485Z","caller":"traceutil/trace.go:171","msg":"trace[1163671127] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"124.14937ms","start":"2026-02-26T01:42:15.822199Z","end":"2026-02-26T01:42:15.946348Z","steps":["trace[1163671127] 'process raft request' (duration: 123.61155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:15.946639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.066469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T01:42:15.946743Z","caller":"traceutil/trace.go:171","msg":"trace[1998331281] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:12; }","duration":"128.241453ms","start":"2026-02-26T01:42:15.818479Z","end":"2026-02-26T01:42:15.946721Z","steps":["trace[1998331281] 'agreement among raft nodes before linearized reading' (duration: 128.065409ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:16.221227Z","caller":"traceutil/trace.go:171","msg":"trace[480552455] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"104.658871ms","start":"2026-02-26T01:42:16.116524Z","end":"2026-02-26T01:42:16.221182Z","steps":["trace[480552455] 'process raft request' (duration: 104.597159ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:16.221395Z","caller":"traceutil/trace.go:171","msg":"trace[509606374] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"108.040089ms","start":"2026-02-26T01:42:16.113292Z","end":"2026-02-26T01:42:16.221332Z","steps":["trace[509606374] 'process raft request' (duration: 104.632631ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:16.221506Z","caller":"traceutil/trace.go:171","msg":"trace[975077716] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"106.09505ms","start":"2026-02-26T01:42:16.115321Z","end":"2026-02-26T01:42:16.221416Z","steps":["trace[975077716] 'process raft request' (duration: 105.743323ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:34.846052Z","caller":"traceutil/trace.go:171","msg":"trace[1479176699] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"283.338132ms","start":"2026-02-26T01:42:34.562677Z","end":"2026-02-26T01:42:34.846015Z","steps":["trace[1479176699] 'process raft request' (duration: 283.133808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:35.314514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.249304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T01:42:35.314621Z","caller":"traceutil/trace.go:171","msg":"trace[487392216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:449; }","duration":"112.404636ms","start":"2026-02-26T01:42:35.202193Z","end":"2026-02-26T01:42:35.314597Z","steps":["trace[487392216] 'range keys from in-memory index tree' (duration: 112.151191ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:41.717155Z","caller":"traceutil/trace.go:171","msg":"trace[1558095947] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"836.028764ms","start":"2026-02-26T01:42:40.881085Z","end":"2026-02-26T01:42:41.717114Z","steps":["trace[1558095947] 'process raft request' (duration: 835.79645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:41.717317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.973895ms","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-26T01:42:41.717152Z","caller":"traceutil/trace.go:171","msg":"trace[1312559392] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"324.776508ms","start":"2026-02-26T01:42:41.392328Z","end":"2026-02-26T01:42:41.717105Z","steps":["trace[1312559392] 'read index received' (duration: 324.554904ms)","trace[1312559392] 'applied index is now lower than readState.Index' (duration: 218.164µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:41.717386Z","caller":"traceutil/trace.go:171","msg":"trace[1962116623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:464; }","duration":"325.103986ms","start":"2026-02-26T01:42:41.392269Z","end":"2026-02-26T01:42:41.717373Z","steps":["trace[1962116623] 'agreement among raft nodes before linearized reading' (duration: 324.948682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:41.717431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:41.392244Z","time spent":"325.178638ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:42:41.71772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.498945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:41.717823Z","caller":"traceutil/trace.go:171","msg":"trace[1112195163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"306.662038ms","start":"2026-02-26T01:42:41.411133Z","end":"2026-02-26T01:42:41.717795Z","steps":["trace[1112195163] 'agreement among raft nodes before linearized reading' (duration: 306.449253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:41.717877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:41.411122Z","time spent":"306.74626ms","remote":"127.0.0.1:37792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:42:41.71816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:40.881051Z","time spent":"836.200278ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:42:46.622658Z","caller":"traceutil/trace.go:171","msg":"trace[2080936683] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"224.976004ms","start":"2026-02-26T01:42:46.397648Z","end":"2026-02-26T01:42:46.622624Z","steps":["trace[2080936683] 'read index received' (duration: 224.81616ms)","trace[2080936683] 'applied index is now lower than readState.Index' (duration: 158.374µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:46.62278Z","caller":"traceutil/trace.go:171","msg":"trace[1613656779] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"446.453159ms","start":"2026-02-26T01:42:46.17625Z","end":"2026-02-26T01:42:46.622703Z","steps":["trace[1613656779] 'process raft request' (duration: 446.175992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:46.622971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.27781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:42:46.623087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:46.176218Z","time spent":"446.704784ms","remote":"127.0.0.1:37944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4570,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:42:46.623098Z","caller":"traceutil/trace.go:171","msg":"trace[2020805579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"225.497005ms","start":"2026-02-26T01:42:46.397569Z","end":"2026-02-26T01:42:46.623066Z","steps":["trace[2020805579] 'agreement among raft nodes before linearized reading' (duration: 225.25101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:46.624768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.062525ms","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-26T01:42:46.624878Z","caller":"traceutil/trace.go:171","msg":"trace[139615686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:510; }","duration":"144.241768ms","start":"2026-02-26T01:42:46.480608Z","end":"2026-02-26T01:42:46.62485Z","steps":["trace[139615686] 'agreement among raft nodes before linearized reading' (duration: 143.955423ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:52.748694Z","caller":"traceutil/trace.go:171","msg":"trace[1489646282] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"279.9442ms","start":"2026-02-26T01:42:52.468716Z","end":"2026-02-26T01:42:52.74866Z","steps":["trace[1489646282] 'process raft request' (duration: 279.692575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:52.933916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.015325ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-26T01:42:52.93596Z","caller":"traceutil/trace.go:171","msg":"trace[1993910363] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"181.049706ms","start":"2026-02-26T01:42:52.754866Z","end":"2026-02-26T01:42:52.935916Z","steps":["trace[1993910363] 'process raft request' (duration: 180.841892ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:54.2417Z","caller":"traceutil/trace.go:171","msg":"trace[936055090] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"375.478406ms","start":"2026-02-26T01:42:53.866164Z","end":"2026-02-26T01:42:54.241642Z","steps":["trace[936055090] 'read index received' (duration: 294.342593ms)","trace[936055090] 'applied index is now lower than readState.Index' (duration: 81.134243ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:54.241843Z","caller":"traceutil/trace.go:171","msg":"trace[172388796] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"377.597357ms","start":"2026-02-26T01:42:53.864128Z","end":"2026-02-26T01:42:54.241725Z","steps":["trace[172388796] 'process raft request' (duration: 296.489245ms)","trace[172388796] 'compare' (duration: 80.885838ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:42:54.242014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:53.864103Z","time spent":"377.817681ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:42:54.242093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.906374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T01:42:54.242222Z","caller":"traceutil/trace.go:171","msg":"trace[693360502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"376.085108ms","start":"2026-02-26T01:42:53.866105Z","end":"2026-02-26T01:42:54.24219Z","steps":["trace[693360502] 'agreement among raft nodes before linearized reading' (duration: 375.737041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:54.24228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:53.866088Z","time spent":"376.178151ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:42:55.910338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.526334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:42:55.910662Z","caller":"traceutil/trace.go:171","msg":"trace[128451509] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"328.155272ms","start":"2026-02-26T01:42:55.582464Z","end":"2026-02-26T01:42:55.910619Z","steps":["trace[128451509] 'process raft request' (duration: 181.21852ms)","trace[128451509] 'compare' (duration: 146.257539ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:55.910712Z","caller":"traceutil/trace.go:171","msg":"trace[604485618] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"328.009769ms","start":"2026-02-26T01:42:55.582667Z","end":"2026-02-26T01:42:55.910677Z","steps":["trace[604485618] 'process raft request' (duration: 327.811036ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:55.91087Z","caller":"traceutil/trace.go:171","msg":"trace[1818552187] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"326.884118ms","start":"2026-02-26T01:42:55.583956Z","end":"2026-02-26T01:42:55.91084Z","steps":["trace[1818552187] 'process raft request' (duration: 326.640043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:55.910931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:55.582445Z","time spent":"328.350906ms","remote":"127.0.0.1:37838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:42:55.910993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:55.583934Z","time spent":"327.01504ms","remote":"127.0.0.1:38278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:42:55.910956Z","caller":"traceutil/trace.go:171","msg":"trace[1959912856] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"148.048065ms","start":"2026-02-26T01:42:55.762853Z","end":"2026-02-26T01:42:55.910901Z","steps":["trace[1959912856] 'process raft request' (duration: 147.79263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:55.910884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:55.582656Z","time spent":"328.151052ms","remote":"127.0.0.1:38108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1111,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:43:14.258723Z","caller":"traceutil/trace.go:171","msg":"trace[1062519103] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"489.391136ms","start":"2026-02-26T01:43:13.769284Z","end":"2026-02-26T01:43:14.258675Z","steps":["trace[1062519103] 'process raft request' (duration: 488.986489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:14.258951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:13.769265Z","time spent":"489.58122ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:43:14.259884Z","caller":"traceutil/trace.go:171","msg":"trace[220998423] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"226.865842ms","start":"2026-02-26T01:43:14.032021Z","end":"2026-02-26T01:43:14.258887Z","steps":["trace[220998423] 'read index received' (duration: 226.174798ms)","trace[220998423] 'applied index is now lower than readState.Index' (duration: 345.017µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:14.260595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.539854ms","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-26T01:43:14.260732Z","caller":"traceutil/trace.go:171","msg":"trace[526575863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:931; }","duration":"228.755959ms","start":"2026-02-26T01:43:14.031945Z","end":"2026-02-26T01:43:14.260701Z","steps":["trace[526575863] 'agreement among raft nodes before linearized reading' (duration: 226.844862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:14.868773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897520519789895,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T01:43:14.968654Z","caller":"traceutil/trace.go:171","msg":"trace[1488715441] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"600.702715ms","start":"2026-02-26T01:43:14.367917Z","end":"2026-02-26T01:43:14.96862Z","steps":["trace[1488715441] 'read index received' (duration: 552.127676ms)","trace[1488715441] 'applied index is now lower than readState.Index' (duration: 48.573499ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:43:14.969126Z","caller":"traceutil/trace.go:171","msg":"trace[1494469661] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"702.080188ms","start":"2026-02-26T01:43:14.26697Z","end":"2026-02-26T01:43:14.96905Z","steps":["trace[1494469661] 'process raft request' (duration: 653.090011ms)","trace[1494469661] 'compare' (duration: 48.396825ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:14.969193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.246326ms","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-26T01:43:14.969323Z","caller":"traceutil/trace.go:171","msg":"trace[75323463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"601.46276ms","start":"2026-02-26T01:43:14.367847Z","end":"2026-02-26T01:43:14.969309Z","steps":["trace[75323463] 'agreement among raft nodes before linearized reading' (duration: 601.164194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:14.969403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:14.266942Z","time spent":"702.304492ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:43:14.969409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:14.367822Z","time spent":"601.574043ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:43:14.969569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.820108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T01:43:14.969673Z","caller":"traceutil/trace.go:171","msg":"trace[1022435375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"600.027663ms","start":"2026-02-26T01:43:14.36962Z","end":"2026-02-26T01:43:14.969647Z","steps":["trace[1022435375] 'agreement among raft nodes before linearized reading' (duration: 599.655155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:14.969738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:14.3696Z","time spent":"600.126225ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T01:43:32.512448Z","caller":"traceutil/trace.go:171","msg":"trace[2636761] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"106.386452ms","start":"2026-02-26T01:43:32.406037Z","end":"2026-02-26T01:43:32.512423Z","steps":["trace[2636761] 'process raft request' (duration: 105.595577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:36.36377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.759101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-jmx4p\" ","response":"range_response_count:1 size:7089"} {"level":"warn","ts":"2026-02-26T01:43:36.363935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.059037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-t5fw7\" ","response":"range_response_count:1 size:4217"} {"level":"info","ts":"2026-02-26T01:43:36.364063Z","caller":"traceutil/trace.go:171","msg":"trace[2124985322] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-t5fw7; range_end:; response_count:1; response_revision:1158; }","duration":"179.21125ms","start":"2026-02-26T01:43:36.184827Z","end":"2026-02-26T01:43:36.364039Z","steps":["trace[2124985322] 'range keys from in-memory index tree' (duration: 178.850823ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:36.363978Z","caller":"traceutil/trace.go:171","msg":"trace[638436809] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-jmx4p; range_end:; response_count:1; response_revision:1158; }","duration":"178.976106ms","start":"2026-02-26T01:43:36.184963Z","end":"2026-02-26T01:43:36.36394Z","steps":["trace[638436809] 'range keys from in-memory index tree' (duration: 178.556547ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:36.364212Z","caller":"traceutil/trace.go:171","msg":"trace[496395108] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"155.42307ms","start":"2026-02-26T01:43:36.20875Z","end":"2026-02-26T01:43:36.364173Z","steps":["trace[496395108] 'process raft request' (duration: 155.121834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:36.363721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.693153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:43:36.364344Z","caller":"traceutil/trace.go:171","msg":"trace[792764597] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1158; }","duration":"169.370806ms","start":"2026-02-26T01:43:36.194961Z","end":"2026-02-26T01:43:36.364331Z","steps":["trace[792764597] 'count revisions from in-memory index tree' (duration: 168.633582ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:36.798013Z","caller":"traceutil/trace.go:171","msg":"trace[1962850554] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"145.614666ms","start":"2026-02-26T01:43:36.652367Z","end":"2026-02-26T01:43:36.797982Z","steps":["trace[1962850554] 'process raft request' (duration: 145.393162ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:36.798934Z","caller":"traceutil/trace.go:171","msg":"trace[1671930055] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1205; }","duration":"107.597935ms","start":"2026-02-26T01:43:36.691305Z","end":"2026-02-26T01:43:36.798903Z","steps":["trace[1671930055] 'read index received' (duration: 106.419512ms)","trace[1671930055] 'applied index is now lower than readState.Index' (duration: 1.177313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:36.800523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.180846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-v4b4k\" ","response":"range_response_count:1 size:3261"} {"level":"info","ts":"2026-02-26T01:43:36.800624Z","caller":"traceutil/trace.go:171","msg":"trace[1342606965] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-patch-v4b4k; range_end:; response_count:1; response_revision:1170; }","duration":"109.339531ms","start":"2026-02-26T01:43:36.691243Z","end":"2026-02-26T01:43:36.800582Z","steps":["trace[1342606965] 'agreement among raft nodes before linearized reading' (duration: 107.759049ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:36.799192Z","caller":"traceutil/trace.go:171","msg":"trace[1503186853] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"108.419252ms","start":"2026-02-26T01:43:36.690521Z","end":"2026-02-26T01:43:36.79894Z","steps":["trace[1503186853] 'process raft request' (duration: 108.225468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:37.720526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.181678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-26T01:43:37.720643Z","caller":"traceutil/trace.go:171","msg":"trace[531471246] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:1183; }","duration":"185.334431ms","start":"2026-02-26T01:43:37.535286Z","end":"2026-02-26T01:43:37.720621Z","steps":["trace[531471246] 'range keys from in-memory index tree' (duration: 185.011895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:38.042632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.016379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1823"} {"level":"info","ts":"2026-02-26T01:43:38.042731Z","caller":"traceutil/trace.go:171","msg":"trace[233214661] range","detail":"{range_begin:/registry/secrets/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1185; }","duration":"129.173321ms","start":"2026-02-26T01:43:37.913535Z","end":"2026-02-26T01:43:38.042708Z","steps":["trace[233214661] 'range keys from in-memory index tree' (duration: 128.817734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:55.434691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.837306ms","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-26T01:43:55.434846Z","caller":"traceutil/trace.go:171","msg":"trace[1520411080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1482; }","duration":"154.054599ms","start":"2026-02-26T01:43:55.280764Z","end":"2026-02-26T01:43:55.434819Z","steps":["trace[1520411080] 'range keys from in-memory index tree' (duration: 153.58478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:02.616056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.109407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:44:02.616335Z","caller":"traceutil/trace.go:171","msg":"trace[15889140] linearizableReadLoop","detail":"{readStateIndex:1659; appliedIndex:1658; }","duration":"632.046625ms","start":"2026-02-26T01:44:01.984264Z","end":"2026-02-26T01:44:02.61631Z","steps":["trace[15889140] 'read index received' (duration: 74.451849ms)","trace[15889140] 'applied index is now lower than readState.Index' (duration: 557.592796ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:44:02.616394Z","caller":"traceutil/trace.go:171","msg":"trace[282519263] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"665.174608ms","start":"2026-02-26T01:44:01.951161Z","end":"2026-02-26T01:44:02.616335Z","steps":["trace[282519263] 'process raft request' (duration: 107.666633ms)","trace[282519263] 'compare' (duration: 556.933863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:44:02.61662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:01.951133Z","time spent":"665.368681ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:44:02.616629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.619159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T01:44:02.616731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.431772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eae1d82c-2faa-49ef-969f-7a664af8be00\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-02-26T01:44:02.616859Z","caller":"traceutil/trace.go:171","msg":"trace[503965286] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eae1d82c-2faa-49ef-969f-7a664af8be00; range_end:; response_count:1; response_revision:1596; }","duration":"632.609196ms","start":"2026-02-26T01:44:01.98422Z","end":"2026-02-26T01:44:02.616829Z","steps":["trace[503965286] 'agreement among raft nodes before linearized reading' (duration: 632.29777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:02.616913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:01.984206Z","time spent":"632.695238ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eae1d82c-2faa-49ef-969f-7a664af8be00\" "} {"level":"info","ts":"2026-02-26T01:44:02.616779Z","caller":"traceutil/trace.go:171","msg":"trace[371737359] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1596; }","duration":"278.814803ms","start":"2026-02-26T01:44:02.337946Z","end":"2026-02-26T01:44:02.616761Z","steps":["trace[371737359] 'agreement among raft nodes before linearized reading' (duration: 278.605929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:02.616702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.986127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:44:02.617088Z","caller":"traceutil/trace.go:171","msg":"trace[1414740358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1596; }","duration":"219.423016ms","start":"2026-02-26T01:44:02.397649Z","end":"2026-02-26T01:44:02.617072Z","steps":["trace[1414740358] 'agreement among raft nodes before linearized reading' (duration: 218.992088ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:03.193557Z","caller":"traceutil/trace.go:171","msg":"trace[1424616613] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"314.16791ms","start":"2026-02-26T01:44:02.879354Z","end":"2026-02-26T01:44:03.193522Z","steps":["trace[1424616613] 'process raft request' (duration: 313.959316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:03.193756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:02.87934Z","time spent":"314.312383ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:44:03.222177Z","caller":"traceutil/trace.go:171","msg":"trace[988257539] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"147.769184ms","start":"2026-02-26T01:44:03.074375Z","end":"2026-02-26T01:44:03.222144Z","steps":["trace[988257539] 'process raft request' (duration: 147.53749ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:52.692865Z","caller":"traceutil/trace.go:171","msg":"trace[1488911909] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"193.10924ms","start":"2026-02-26T01:44:52.499665Z","end":"2026-02-26T01:44:52.692774Z","steps":["trace[1488911909] 'process raft request' (duration: 192.949157ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:59.044312Z","caller":"traceutil/trace.go:171","msg":"trace[1914288407] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"143.876276ms","start":"2026-02-26T01:44:58.900409Z","end":"2026-02-26T01:44:59.044286Z","steps":["trace[1914288407] 'process raft request' (duration: 143.684982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:59.607765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.113601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:44:59.607866Z","caller":"traceutil/trace.go:171","msg":"trace[1072880031] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"559.313931ms","start":"2026-02-26T01:44:59.048537Z","end":"2026-02-26T01:44:59.607851Z","steps":["trace[1072880031] 'process raft request' (duration: 416.023407ms)","trace[1072880031] 'compare' (duration: 142.983688ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:44:59.60791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:59.048518Z","time spent":"559.373842ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:45:00.235648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.720373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:45:00.235895Z","caller":"traceutil/trace.go:171","msg":"trace[2040441750] linearizableReadLoop","detail":"{readStateIndex:2001; appliedIndex:1999; }","duration":"838.076413ms","start":"2026-02-26T01:44:59.397801Z","end":"2026-02-26T01:45:00.235877Z","steps":["trace[2040441750] 'read index received' (duration: 66.771484ms)","trace[2040441750] 'applied index is now lower than readState.Index' (duration: 771.303539ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:00.235938Z","caller":"traceutil/trace.go:171","msg":"trace[758866287] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"794.170155ms","start":"2026-02-26T01:44:59.441733Z","end":"2026-02-26T01:45:00.235903Z","steps":["trace[758866287] 'process raft request' (duration: 794.013192ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:00.23606Z","caller":"traceutil/trace.go:171","msg":"trace[1149449825] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"942.813956ms","start":"2026-02-26T01:44:59.293148Z","end":"2026-02-26T01:45:00.235962Z","steps":["trace[1149449825] 'process raft request' (duration: 804.661435ms)","trace[1149449825] 'compare' (duration: 137.289034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:45:00.23627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:59.293118Z","time spent":"943.052321ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:45:00.236437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.74663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-26T01:45:00.236427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.503804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T01:45:00.236449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.954984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T01:45:00.236437Z","caller":"traceutil/trace.go:171","msg":"trace[65837335] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"207.282964ms","start":"2026-02-26T01:45:00.029108Z","end":"2026-02-26T01:45:00.236391Z","steps":["trace[65837335] 'process raft request' (duration: 206.736922ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:00.236484Z","caller":"traceutil/trace.go:171","msg":"trace[1329037630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1926; }","duration":"572.840481ms","start":"2026-02-26T01:44:59.663633Z","end":"2026-02-26T01:45:00.236474Z","steps":["trace[1329037630] 'agreement among raft nodes before linearized reading' (duration: 572.687887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:00.236488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.826864ms","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-26T01:45:00.236478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.666286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:45:00.236509Z","caller":"traceutil/trace.go:171","msg":"trace[1992264449] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1926; }","duration":"734.620266ms","start":"2026-02-26T01:44:59.501869Z","end":"2026-02-26T01:45:00.236489Z","steps":["trace[1992264449] 'agreement among raft nodes before linearized reading' (duration: 734.459541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:00.236523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:59.663607Z","time spent":"572.907873ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T01:45:00.236542Z","caller":"traceutil/trace.go:171","msg":"trace[1943966931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1926; }","duration":"253.916036ms","start":"2026-02-26T01:44:59.98261Z","end":"2026-02-26T01:45:00.236526Z","steps":["trace[1943966931] 'agreement among raft nodes before linearized reading' (duration: 253.704651ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:00.236535Z","caller":"traceutil/trace.go:171","msg":"trace[873861479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1926; }","duration":"573.046936ms","start":"2026-02-26T01:44:59.663459Z","end":"2026-02-26T01:45:00.236505Z","steps":["trace[873861479] 'agreement among raft nodes before linearized reading' (duration: 572.834111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:00.236523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.255104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-02-26T01:45:00.236525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.674318ms","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-26T01:45:00.236561Z","caller":"traceutil/trace.go:171","msg":"trace[1448014210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1926; }","duration":"838.796898ms","start":"2026-02-26T01:44:59.397741Z","end":"2026-02-26T01:45:00.236538Z","steps":["trace[1448014210] 'agreement among raft nodes before linearized reading' (duration: 838.237407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:00.236572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:59.501859Z","time spent":"734.702248ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":10,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:45:00.236597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:59.66345Z","time spent":"573.130947ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T01:45:00.236611Z","caller":"traceutil/trace.go:171","msg":"trace[2082313729] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1926; }","duration":"184.373825ms","start":"2026-02-26T01:45:00.052213Z","end":"2026-02-26T01:45:00.236586Z","steps":["trace[2082313729] 'agreement among raft nodes before linearized reading' (duration: 184.076159ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:00.236621Z","caller":"traceutil/trace.go:171","msg":"trace[1684368463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1926; }","duration":"573.812571ms","start":"2026-02-26T01:44:59.662784Z","end":"2026-02-26T01:45:00.236597Z","steps":["trace[1684368463] 'agreement among raft nodes before linearized reading' (duration: 573.528874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:00.23662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:59.397719Z","time spent":"838.8858ms","remote":"127.0.0.1:37792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:45:00.236664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:59.662741Z","time spent":"573.915033ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:45:00.23702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:59.441715Z","time spent":"794.324518ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:45:00.237086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.487242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:45:00.237127Z","caller":"traceutil/trace.go:171","msg":"trace[1667724345] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1926; }","duration":"838.589404ms","start":"2026-02-26T01:44:59.398525Z","end":"2026-02-26T01:45:00.237115Z","steps":["trace[1667724345] 'agreement among raft nodes before linearized reading' (duration: 837.667805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:00.237163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:59.39851Z","time spent":"838.645105ms","remote":"127.0.0.1:38008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:45:36.136331Z","caller":"traceutil/trace.go:171","msg":"trace[1235102740] linearizableReadLoop","detail":"{readStateIndex:2370; appliedIndex:2369; }","duration":"336.105819ms","start":"2026-02-26T01:45:35.800199Z","end":"2026-02-26T01:45:36.136305Z","steps":["trace[1235102740] 'read index received' (duration: 335.922655ms)","trace[1235102740] 'applied index is now lower than readState.Index' (duration: 176.524µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:45:36.13639Z","caller":"traceutil/trace.go:171","msg":"trace[859411890] transaction","detail":"{read_only:false; response_revision:2277; number_of_response:1; }","duration":"470.751801ms","start":"2026-02-26T01:45:35.665597Z","end":"2026-02-26T01:45:36.136349Z","steps":["trace[859411890] 'process raft request' (duration: 470.563127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:36.136531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.325873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-26T01:45:36.136555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:35.665572Z","time spent":"470.895923ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:45:36.13658Z","caller":"traceutil/trace.go:171","msg":"trace[469075245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2277; }","duration":"336.424285ms","start":"2026-02-26T01:45:35.800132Z","end":"2026-02-26T01:45:36.136556Z","steps":["trace[469075245] 'agreement among raft nodes before linearized reading' (duration: 336.277682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:45:36.136621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:45:35.800107Z","time spent":"336.502657ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T01:45:36.263825Z","caller":"traceutil/trace.go:171","msg":"trace[1276344033] transaction","detail":"{read_only:false; response_revision:2278; number_of_response:1; }","duration":"122.479539ms","start":"2026-02-26T01:45:36.141326Z","end":"2026-02-26T01:45:36.263806Z","steps":["trace[1276344033] 'process raft request' (duration: 121.946279ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:06.331021Z","caller":"traceutil/trace.go:171","msg":"trace[1321795683] transaction","detail":"{read_only:false; response_revision:2446; number_of_response:1; }","duration":"141.528181ms","start":"2026-02-26T01:46:06.18947Z","end":"2026-02-26T01:46:06.330998Z","steps":["trace[1321795683] 'process raft request' (duration: 141.349748ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:37.541316Z","caller":"traceutil/trace.go:171","msg":"trace[657326390] transaction","detail":"{read_only:false; response_revision:3744; number_of_response:1; }","duration":"215.763284ms","start":"2026-02-26T01:49:37.325469Z","end":"2026-02-26T01:49:37.541232Z","steps":["trace[657326390] 'process raft request' (duration: 215.388246ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:57.596541Z","caller":"traceutil/trace.go:171","msg":"trace[1729775585] transaction","detail":"{read_only:false; response_revision:4262; number_of_response:1; }","duration":"168.596795ms","start":"2026-02-26T01:49:57.427917Z","end":"2026-02-26T01:49:57.596514Z","steps":["trace[1729775585] 'process raft request' (duration: 168.427171ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:57.596955Z","caller":"traceutil/trace.go:171","msg":"trace[1159980772] linearizableReadLoop","detail":"{readStateIndex:4434; appliedIndex:4434; }","duration":"100.307016ms","start":"2026-02-26T01:49:57.496607Z","end":"2026-02-26T01:49:57.596914Z","steps":["trace[1159980772] 'read index received' (duration: 100.294386ms)","trace[1159980772] 'applied index is now lower than readState.Index' (duration: 10.33µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:49:57.597354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.731174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-26T01:49:57.597451Z","caller":"traceutil/trace.go:171","msg":"trace[1126322445] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4262; }","duration":"100.881858ms","start":"2026-02-26T01:49:57.496551Z","end":"2026-02-26T01:49:57.597432Z","steps":["trace[1126322445] 'agreement among raft nodes before linearized reading' (duration: 100.49264ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:03.028067Z","caller":"traceutil/trace.go:171","msg":"trace[1003951100] transaction","detail":"{read_only:false; response_revision:4391; number_of_response:1; }","duration":"113.485969ms","start":"2026-02-26T01:50:02.914517Z","end":"2026-02-26T01:50:03.028003Z","steps":["trace[1003951100] 'process raft request' (duration: 113.288305ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:03.283917Z","caller":"traceutil/trace.go:171","msg":"trace[1022005118] transaction","detail":"{read_only:false; response_revision:4392; number_of_response:1; }","duration":"138.571957ms","start":"2026-02-26T01:50:03.145308Z","end":"2026-02-26T01:50:03.28388Z","steps":["trace[1022005118] 'process raft request' (duration: 138.24709ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:04.513402Z","caller":"traceutil/trace.go:171","msg":"trace[1428826388] transaction","detail":"{read_only:false; response_revision:4402; number_of_response:1; }","duration":"229.259483ms","start":"2026-02-26T01:50:04.284113Z","end":"2026-02-26T01:50:04.513373Z","steps":["trace[1428826388] 'process raft request' (duration: 229.077959ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:04.521419Z","caller":"traceutil/trace.go:171","msg":"trace[744572064] linearizableReadLoop","detail":"{readStateIndex:4580; appliedIndex:4578; }","duration":"124.328194ms","start":"2026-02-26T01:50:04.397064Z","end":"2026-02-26T01:50:04.521393Z","steps":["trace[744572064] 'read index received' (duration: 116.124341ms)","trace[744572064] 'applied index is now lower than readState.Index' (duration: 8.202873ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:04.521535Z","caller":"traceutil/trace.go:171","msg":"trace[1123810406] transaction","detail":"{read_only:false; response_revision:4403; number_of_response:1; }","duration":"220.305025ms","start":"2026-02-26T01:50:04.301218Z","end":"2026-02-26T01:50:04.521524Z","steps":["trace[1123810406] 'process raft request' (duration: 220.04352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:04.521652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.58203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:50:04.521708Z","caller":"traceutil/trace.go:171","msg":"trace[1515781149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4403; }","duration":"124.692211ms","start":"2026-02-26T01:50:04.397003Z","end":"2026-02-26T01:50:04.521696Z","steps":["trace[1515781149] 'agreement among raft nodes before linearized reading' (duration: 124.6052ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:08.231142Z","caller":"traceutil/trace.go:171","msg":"trace[572109962] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"447.803043ms","start":"2026-02-26T01:50:07.783304Z","end":"2026-02-26T01:50:08.231107Z","steps":["trace[572109962] 'process raft request' (duration: 416.428528ms)","trace[572109962] 'compare' (duration: 30.941996ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:08.231135Z","caller":"traceutil/trace.go:171","msg":"trace[237485601] linearizableReadLoop","detail":"{readStateIndex:4703; appliedIndex:4702; }","duration":"446.856102ms","start":"2026-02-26T01:50:07.784245Z","end":"2026-02-26T01:50:08.231101Z","steps":["trace[237485601] 'read index received' (duration: 415.478168ms)","trace[237485601] 'applied index is now lower than readState.Index' (duration: 31.376364ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:08.231297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:07.783287Z","time spent":"447.932575ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3705,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:50:08.231371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.117767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T01:50:08.231428Z","caller":"traceutil/trace.go:171","msg":"trace[1255268615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4523; }","duration":"447.19442ms","start":"2026-02-26T01:50:07.784217Z","end":"2026-02-26T01:50:08.231411Z","steps":["trace[1255268615] 'agreement among raft nodes before linearized reading' (duration: 446.970685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:08.231421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.822652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-26T01:50:08.231463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:07.784204Z","time spent":"447.25153ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T01:50:08.231475Z","caller":"traceutil/trace.go:171","msg":"trace[1628617478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4523; }","duration":"349.921744ms","start":"2026-02-26T01:50:07.881537Z","end":"2026-02-26T01:50:08.231458Z","steps":["trace[1628617478] 'agreement among raft nodes before linearized reading' (duration: 349.731441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:08.231522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:07.881519Z","time spent":"349.990905ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T01:50:08.231713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.17335ms","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-26T01:50:08.231756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.095108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-26T01:50:08.231854Z","caller":"traceutil/trace.go:171","msg":"trace[892535315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4523; }","duration":"348.239231ms","start":"2026-02-26T01:50:07.88359Z","end":"2026-02-26T01:50:08.231829Z","steps":["trace[892535315] 'agreement among raft nodes before linearized reading' (duration: 347.983226ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:08.23179Z","caller":"traceutil/trace.go:171","msg":"trace[712114257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4523; }","duration":"347.275802ms","start":"2026-02-26T01:50:07.884493Z","end":"2026-02-26T01:50:08.231769Z","steps":["trace[712114257] 'agreement among raft nodes before linearized reading' (duration: 347.049207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:08.231901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:07.883566Z","time spent":"348.324752ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:50:08.231961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:07.884483Z","time spent":"347.456995ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:50:08.231965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.230341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-26T01:50:08.232048Z","caller":"traceutil/trace.go:171","msg":"trace[934617601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4523; }","duration":"350.339893ms","start":"2026-02-26T01:50:07.881689Z","end":"2026-02-26T01:50:08.232029Z","steps":["trace[934617601] 'agreement among raft nodes before linearized reading' (duration: 350.067777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:08.232098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:07.881682Z","time spent":"350.404894ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T01:50:08.232199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.951243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:718781"} {"level":"info","ts":"2026-02-26T01:50:08.23227Z","caller":"traceutil/trace.go:171","msg":"trace[1692962347] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4523; }","duration":"167.040764ms","start":"2026-02-26T01:50:08.065198Z","end":"2026-02-26T01:50:08.232238Z","steps":["trace[1692962347] 'agreement among raft nodes before linearized reading' (duration: 166.574095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:08.720176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.914225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-02-26T01:50:08.720257Z","caller":"traceutil/trace.go:171","msg":"trace[925267710] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4528; }","duration":"341.043627ms","start":"2026-02-26T01:50:08.379195Z","end":"2026-02-26T01:50:08.720239Z","steps":["trace[925267710] 'range keys from in-memory index tree' (duration: 340.712331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:08.720298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:08.379174Z","time spent":"341.114149ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-02-26T01:50:08.761867Z","caller":"traceutil/trace.go:171","msg":"trace[1100018822] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"206.407947ms","start":"2026-02-26T01:50:08.555425Z","end":"2026-02-26T01:50:08.761833Z","steps":["trace[1100018822] 'process raft request' (duration: 206.171043ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:09.525887Z","caller":"traceutil/trace.go:171","msg":"trace[1098418222] transaction","detail":"{read_only:false; response_revision:4533; number_of_response:1; }","duration":"102.722084ms","start":"2026-02-26T01:50:09.423139Z","end":"2026-02-26T01:50:09.525861Z","steps":["trace[1098418222] 'process raft request' (duration: 102.105752ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:10.897164Z","caller":"traceutil/trace.go:171","msg":"trace[998728494] linearizableReadLoop","detail":"{readStateIndex:4754; appliedIndex:4753; }","duration":"125.677592ms","start":"2026-02-26T01:50:10.77146Z","end":"2026-02-26T01:50:10.897138Z","steps":["trace[998728494] 'read index received' (duration: 124.758263ms)","trace[998728494] 'applied index is now lower than readState.Index' (duration: 917.999µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:10.897295Z","caller":"traceutil/trace.go:171","msg":"trace[1976013565] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"130.065159ms","start":"2026-02-26T01:50:10.76717Z","end":"2026-02-26T01:50:10.897235Z","steps":["trace[1976013565] 'process raft request' (duration: 129.118049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:10.897425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.947837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-01cb46c4-383d-4088-ba5d-487c23a81545\" ","response":"range_response_count:1 size:3906"} {"level":"info","ts":"2026-02-26T01:50:10.897465Z","caller":"traceutil/trace.go:171","msg":"trace[1919693178] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-01cb46c4-383d-4088-ba5d-487c23a81545; range_end:; response_count:1; response_revision:4573; }","duration":"126.044609ms","start":"2026-02-26T01:50:10.771408Z","end":"2026-02-26T01:50:10.897452Z","steps":["trace[1919693178] 'agreement among raft nodes before linearized reading' (duration: 125.826235ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:18.645497Z","caller":"traceutil/trace.go:171","msg":"trace[1952468336] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4715; }","duration":"126.340224ms","start":"2026-02-26T01:50:18.519131Z","end":"2026-02-26T01:50:18.645471Z","steps":["trace[1952468336] 'process raft request' (duration: 126.224791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:19.846865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.561038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:50:19.847011Z","caller":"traceutil/trace.go:171","msg":"trace[678504031] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"114.354214ms","start":"2026-02-26T01:50:19.732637Z","end":"2026-02-26T01:50:19.846991Z","steps":["trace[678504031] 'compare' (duration: 104.356884ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:20.204021Z","caller":"traceutil/trace.go:171","msg":"trace[1520695713] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"101.359895ms","start":"2026-02-26T01:50:20.102637Z","end":"2026-02-26T01:50:20.203997Z","steps":["trace[1520695713] 'process raft request' (duration: 101.318004ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:20.204509Z","caller":"traceutil/trace.go:171","msg":"trace[1743617185] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"101.976266ms","start":"2026-02-26T01:50:20.102496Z","end":"2026-02-26T01:50:20.204472Z","steps":["trace[1743617185] 'process raft request' (duration: 101.412145ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:20.204617Z","caller":"traceutil/trace.go:171","msg":"trace[199245345] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"102.222492ms","start":"2026-02-26T01:50:20.102333Z","end":"2026-02-26T01:50:20.204555Z","steps":["trace[199245345] 'process raft request' (duration: 90.728022ms)","trace[199245345] 'compare' (duration: 10.743205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:20.560467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.538285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T01:50:20.560672Z","caller":"traceutil/trace.go:171","msg":"trace[173694380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4754; }","duration":"198.805711ms","start":"2026-02-26T01:50:20.361841Z","end":"2026-02-26T01:50:20.560647Z","steps":["trace[173694380] 'range keys from in-memory index tree' (duration: 198.327121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:20.560661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.582406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-26T01:50:20.560872Z","caller":"traceutil/trace.go:171","msg":"trace[252296995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4754; }","duration":"191.859182ms","start":"2026-02-26T01:50:20.368995Z","end":"2026-02-26T01:50:20.560854Z","steps":["trace[252296995] 'range keys from in-memory index tree' (duration: 191.395333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:20.561016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.919973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-26T01:50:20.561133Z","caller":"traceutil/trace.go:171","msg":"trace[711395608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4754; }","duration":"196.194439ms","start":"2026-02-26T01:50:20.364912Z","end":"2026-02-26T01:50:20.561106Z","steps":["trace[711395608] 'range keys from in-memory index tree' (duration: 195.432083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:20.561284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.514505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:50:20.561358Z","caller":"traceutil/trace.go:171","msg":"trace[1106509669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4754; }","duration":"200.589867ms","start":"2026-02-26T01:50:20.360757Z","end":"2026-02-26T01:50:20.561347Z","steps":["trace[1106509669] 'range keys from in-memory index tree' (duration: 199.613437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:20.561538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.987385ms","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-26T01:50:20.561603Z","caller":"traceutil/trace.go:171","msg":"trace[392981832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4754; }","duration":"192.081547ms","start":"2026-02-26T01:50:20.369506Z","end":"2026-02-26T01:50:20.561588Z","steps":["trace[392981832] 'range keys from in-memory index tree' (duration: 191.828222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:22.866632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.37777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:50:22.866809Z","caller":"traceutil/trace.go:171","msg":"trace[1827148448] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"280.947052ms","start":"2026-02-26T01:50:22.585838Z","end":"2026-02-26T01:50:22.866785Z","steps":["trace[1827148448] 'process raft request' (duration: 18.319487ms)","trace[1827148448] 'compare' (duration: 262.173705ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:22.867829Z","caller":"traceutil/trace.go:171","msg":"trace[452230332] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"275.067274ms","start":"2026-02-26T01:50:22.592736Z","end":"2026-02-26T01:50:22.867803Z","steps":["trace[452230332] 'process raft request' (duration: 274.951982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:28.512583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.340982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:50:28.512707Z","caller":"traceutil/trace.go:171","msg":"trace[965146486] linearizableReadLoop","detail":"{readStateIndex:5102; appliedIndex:5101; }","duration":"256.151047ms","start":"2026-02-26T01:50:28.256539Z","end":"2026-02-26T01:50:28.51269Z","steps":["trace[965146486] 'read index received' (duration: 78.51755ms)","trace[965146486] 'applied index is now lower than readState.Index' (duration: 177.632577ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:28.513026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.466783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0606c400-395c-43d1-ac54-107992364285\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-02-26T01:50:28.513115Z","caller":"traceutil/trace.go:171","msg":"trace[728960558] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0606c400-395c-43d1-ac54-107992364285; range_end:; response_count:1; response_revision:4910; }","duration":"256.608186ms","start":"2026-02-26T01:50:28.256487Z","end":"2026-02-26T01:50:28.513095Z","steps":["trace[728960558] 'agreement among raft nodes before linearized reading' (duration: 256.442043ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:28.513187Z","caller":"traceutil/trace.go:171","msg":"trace[1711777031] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"419.318495ms","start":"2026-02-26T01:50:28.093848Z","end":"2026-02-26T01:50:28.513167Z","steps":["trace[1711777031] 'process raft request' (duration: 241.28686ms)","trace[1711777031] 'compare' (duration: 177.212459ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:28.513284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:28.093822Z","time spent":"419.420727ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:50:28.513053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.039838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:50:28.513449Z","caller":"traceutil/trace.go:171","msg":"trace[468051204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4910; }","duration":"116.493326ms","start":"2026-02-26T01:50:28.396946Z","end":"2026-02-26T01:50:28.513439Z","steps":["trace[468051204] 'agreement among raft nodes before linearized reading' (duration: 116.056507ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:29.113773Z","caller":"traceutil/trace.go:171","msg":"trace[1994084298] linearizableReadLoop","detail":"{readStateIndex:5103; appliedIndex:5102; }","duration":"470.569798ms","start":"2026-02-26T01:50:28.643169Z","end":"2026-02-26T01:50:29.113739Z","steps":["trace[1994084298] 'read index received' (duration: 470.524587ms)","trace[1994084298] 'applied index is now lower than readState.Index' (duration: 43.911µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:29.113895Z","caller":"traceutil/trace.go:171","msg":"trace[1006012356] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"588.962914ms","start":"2026-02-26T01:50:28.524921Z","end":"2026-02-26T01:50:29.113884Z","steps":["trace[1006012356] 'process raft request' (duration: 588.657737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:29.114017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:28.5249Z","time spent":"589.024395ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4982,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:50:29.114252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.070128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-26T01:50:29.114331Z","caller":"traceutil/trace.go:171","msg":"trace[2099275376] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4911; }","duration":"471.1585ms","start":"2026-02-26T01:50:28.643155Z","end":"2026-02-26T01:50:29.114313Z","steps":["trace[2099275376] 'agreement among raft nodes before linearized reading' (duration: 470.914265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:29.114413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:28.643112Z","time spent":"471.257233ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T01:50:29.127317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.62085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T01:50:29.12738Z","caller":"traceutil/trace.go:171","msg":"trace[1652769884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4912; }","duration":"451.736752ms","start":"2026-02-26T01:50:28.675629Z","end":"2026-02-26T01:50:29.127366Z","steps":["trace[1652769884] 'agreement among raft nodes before linearized reading' (duration: 451.549298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:29.127395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.688442ms","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-26T01:50:29.127389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.454702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-26T01:50:29.127417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:28.675607Z","time spent":"451.803083ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T01:50:29.127429Z","caller":"traceutil/trace.go:171","msg":"trace[108603025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4912; }","duration":"451.767942ms","start":"2026-02-26T01:50:28.675654Z","end":"2026-02-26T01:50:29.127422Z","steps":["trace[108603025] 'agreement among raft nodes before linearized reading' (duration: 451.67147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:29.127449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:28.675632Z","time spent":"451.811914ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T01:50:29.127446Z","caller":"traceutil/trace.go:171","msg":"trace[1654624163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4912; }","duration":"235.549214ms","start":"2026-02-26T01:50:28.891881Z","end":"2026-02-26T01:50:29.12743Z","steps":["trace[1654624163] 'agreement among raft nodes before linearized reading' (duration: 235.385801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:29.12739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.069694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:50:29.127513Z","caller":"traceutil/trace.go:171","msg":"trace[1457251402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4912; }","duration":"239.253608ms","start":"2026-02-26T01:50:28.888253Z","end":"2026-02-26T01:50:29.127507Z","steps":["trace[1457251402] 'agreement among raft nodes before linearized reading' (duration: 239.044064ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:29.127527Z","caller":"traceutil/trace.go:171","msg":"trace[524961235] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"129.171079ms","start":"2026-02-26T01:50:28.998314Z","end":"2026-02-26T01:50:29.127485Z","steps":["trace[524961235] 'process raft request' (duration: 128.731121ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:29.291317Z","caller":"traceutil/trace.go:171","msg":"trace[575787927] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"177.099067ms","start":"2026-02-26T01:50:29.114197Z","end":"2026-02-26T01:50:29.291296Z","steps":["trace[575787927] 'process raft request' (duration: 171.058936ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:29.291329Z","caller":"traceutil/trace.go:171","msg":"trace[767514223] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"171.861682ms","start":"2026-02-26T01:50:29.11944Z","end":"2026-02-26T01:50:29.291302Z","steps":["trace[767514223] 'process raft request' (duration: 171.74005ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:29.291653Z","caller":"traceutil/trace.go:171","msg":"trace[783598487] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"170.429804ms","start":"2026-02-26T01:50:29.121211Z","end":"2026-02-26T01:50:29.291641Z","steps":["trace[783598487] 'process raft request' (duration: 170.028196ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:29.291675Z","caller":"traceutil/trace.go:171","msg":"trace[1361420716] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"168.960484ms","start":"2026-02-26T01:50:29.122701Z","end":"2026-02-26T01:50:29.291661Z","steps":["trace[1361420716] 'process raft request' (duration: 168.911973ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:29.291798Z","caller":"traceutil/trace.go:171","msg":"trace[83690862] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"170.424714ms","start":"2026-02-26T01:50:29.121349Z","end":"2026-02-26T01:50:29.291774Z","steps":["trace[83690862] 'process raft request' (duration: 170.165959ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:29.320644Z","caller":"traceutil/trace.go:171","msg":"trace[1930538864] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"189.969833ms","start":"2026-02-26T01:50:29.130655Z","end":"2026-02-26T01:50:29.320624Z","steps":["trace[1930538864] 'process raft request' (duration: 189.81013ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:29.320956Z","caller":"traceutil/trace.go:171","msg":"trace[350750963] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"190.240579ms","start":"2026-02-26T01:50:29.130681Z","end":"2026-02-26T01:50:29.320922Z","steps":["trace[350750963] 'process raft request' (duration: 189.897942ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:29.321176Z","caller":"traceutil/trace.go:171","msg":"trace[588512248] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"189.988324ms","start":"2026-02-26T01:50:29.131173Z","end":"2026-02-26T01:50:29.321162Z","steps":["trace[588512248] 'process raft request' (duration: 189.892212ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:29.321161Z","caller":"traceutil/trace.go:171","msg":"trace[1429171498] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5104; }","duration":"105.943466ms","start":"2026-02-26T01:50:29.215194Z","end":"2026-02-26T01:50:29.321137Z","steps":["trace[1429171498] 'read index received' (duration: 70.07817ms)","trace[1429171498] 'applied index is now lower than readState.Index' (duration: 35.863736ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:29.321177Z","caller":"traceutil/trace.go:171","msg":"trace[100146774] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"190.347981ms","start":"2026-02-26T01:50:29.130808Z","end":"2026-02-26T01:50:29.321156Z","steps":["trace[100146774] 'process raft request' (duration: 190.133506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:29.321588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.349464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-02-26T01:50:29.321646Z","caller":"traceutil/trace.go:171","msg":"trace[1936246565] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4921; }","duration":"106.488897ms","start":"2026-02-26T01:50:29.215144Z","end":"2026-02-26T01:50:29.321633Z","steps":["trace[1936246565] 'agreement among raft nodes before linearized reading' (duration: 106.066658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:31.442334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.607529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-26T01:50:31.442424Z","caller":"traceutil/trace.go:171","msg":"trace[1232108781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4940; }","duration":"117.738292ms","start":"2026-02-26T01:50:31.324667Z","end":"2026-02-26T01:50:31.442405Z","steps":["trace[1232108781] 'range keys from in-memory index tree' (duration: 117.410035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:31.44246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.419735ms","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-26T01:50:31.442554Z","caller":"traceutil/trace.go:171","msg":"trace[2083388397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4940; }","duration":"117.568878ms","start":"2026-02-26T01:50:31.324965Z","end":"2026-02-26T01:50:31.442533Z","steps":["trace[2083388397] 'range keys from in-memory index tree' (duration: 117.199601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:31.442408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.859844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:50:31.442749Z","caller":"traceutil/trace.go:171","msg":"trace[599879702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4940; }","duration":"117.236612ms","start":"2026-02-26T01:50:31.32549Z","end":"2026-02-26T01:50:31.442727Z","steps":["trace[599879702] 'range keys from in-memory index tree' (duration: 116.686481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:31.442988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.73992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-26T01:50:31.443032Z","caller":"traceutil/trace.go:171","msg":"trace[863429047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4940; }","duration":"146.825562ms","start":"2026-02-26T01:50:31.296195Z","end":"2026-02-26T01:50:31.443021Z","steps":["trace[863429047] 'range keys from in-memory index tree' (duration: 146.618328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:31.442682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.514677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T01:50:31.443807Z","caller":"traceutil/trace.go:171","msg":"trace[1514798432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4940; }","duration":"118.068009ms","start":"2026-02-26T01:50:31.325133Z","end":"2026-02-26T01:50:31.443201Z","steps":["trace[1514798432] 'range keys from in-memory index tree' (duration: 117.340643ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:39.078388Z","caller":"traceutil/trace.go:171","msg":"trace[62665497] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"124.017557ms","start":"2026-02-26T01:50:38.954333Z","end":"2026-02-26T01:50:39.078351Z","steps":["trace[62665497] 'process raft request' (duration: 123.740121ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:52:14.427361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2783} {"level":"info","ts":"2026-02-26T01:52:14.485591Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2783,"took":"56.684581ms","hash":3384658159,"current-db-size-bytes":41926656,"current-db-size":"42 MB","current-db-size-in-use-bytes":31608832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-02-26T01:52:14.485646Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3384658159,"revision":2783,"compact-revision":-1} {"level":"info","ts":"2026-02-26T01:56:33.553202Z","caller":"traceutil/trace.go:171","msg":"trace[1765410166] transaction","detail":"{read_only:false; response_revision:7554; number_of_response:1; }","duration":"139.373715ms","start":"2026-02-26T01:56:33.413803Z","end":"2026-02-26T01:56:33.553177Z","steps":["trace[1765410166] 'process raft request' (duration: 138.664561ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:33.553356Z","caller":"traceutil/trace.go:171","msg":"trace[767371703] transaction","detail":"{read_only:false; response_revision:7555; number_of_response:1; }","duration":"130.091549ms","start":"2026-02-26T01:56:33.423254Z","end":"2026-02-26T01:56:33.553345Z","steps":["trace[767371703] 'process raft request' (duration: 129.488937ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:37.700216Z","caller":"traceutil/trace.go:171","msg":"trace[1222120337] linearizableReadLoop","detail":"{readStateIndex:7857; appliedIndex:7856; }","duration":"289.092034ms","start":"2026-02-26T01:56:37.411094Z","end":"2026-02-26T01:56:37.700186Z","steps":["trace[1222120337] 'read index received' (duration: 284.794809ms)","trace[1222120337] 'applied index is now lower than readState.Index' (duration: 4.295225ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:56:37.700491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.704507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-26T01:56:37.700542Z","caller":"traceutil/trace.go:171","msg":"trace[8720805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7577; }","duration":"278.801959ms","start":"2026-02-26T01:56:37.421725Z","end":"2026-02-26T01:56:37.700527Z","steps":["trace[8720805] 'agreement among raft nodes before linearized reading' (duration: 278.641705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:37.700558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.462701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-26T01:56:37.70061Z","caller":"traceutil/trace.go:171","msg":"trace[128879342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7577; }","duration":"289.555784ms","start":"2026-02-26T01:56:37.411041Z","end":"2026-02-26T01:56:37.700596Z","steps":["trace[128879342] 'agreement among raft nodes before linearized reading' (duration: 289.290468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:37.700761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.242571ms","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-26T01:56:37.700749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.289691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-26T01:56:37.700789Z","caller":"traceutil/trace.go:171","msg":"trace[184517169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7577; }","duration":"208.305153ms","start":"2026-02-26T01:56:37.492476Z","end":"2026-02-26T01:56:37.700781Z","steps":["trace[184517169] 'agreement among raft nodes before linearized reading' (duration: 208.216811ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:37.700832Z","caller":"traceutil/trace.go:171","msg":"trace[1659994608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7577; }","duration":"207.418674ms","start":"2026-02-26T01:56:37.493394Z","end":"2026-02-26T01:56:37.700813Z","steps":["trace[1659994608] 'agreement among raft nodes before linearized reading' (duration: 207.142178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:37.70088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.127212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-26T01:56:37.700955Z","caller":"traceutil/trace.go:171","msg":"trace[1217709266] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:7577; }","duration":"126.247944ms","start":"2026-02-26T01:56:37.574686Z","end":"2026-02-26T01:56:37.700934Z","steps":["trace[1217709266] 'agreement among raft nodes before linearized reading' (duration: 126.026219ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:37.700994Z","caller":"traceutil/trace.go:171","msg":"trace[516413472] transaction","detail":"{read_only:false; response_revision:7577; number_of_response:1; }","duration":"301.968769ms","start":"2026-02-26T01:56:37.399006Z","end":"2026-02-26T01:56:37.700974Z","steps":["trace[516413472] 'process raft request' (duration: 296.869138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:37.700999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.747201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-26T01:56:37.70109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:56:37.398974Z","time spent":"302.051761ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:56:37.701113Z","caller":"traceutil/trace.go:171","msg":"trace[797965054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7577; }","duration":"207.898835ms","start":"2026-02-26T01:56:37.493192Z","end":"2026-02-26T01:56:37.701091Z","steps":["trace[797965054] 'agreement among raft nodes before linearized reading' (duration: 207.599949ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:37.919131Z","caller":"traceutil/trace.go:171","msg":"trace[1029598813] transaction","detail":"{read_only:false; response_revision:7578; number_of_response:1; }","duration":"213.917263ms","start":"2026-02-26T01:56:37.705192Z","end":"2026-02-26T01:56:37.91911Z","steps":["trace[1029598813] 'process raft request' (duration: 213.759469ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:37.919418Z","caller":"traceutil/trace.go:171","msg":"trace[802355918] transaction","detail":"{read_only:false; response_revision:7580; number_of_response:1; }","duration":"211.847852ms","start":"2026-02-26T01:56:37.707559Z","end":"2026-02-26T01:56:37.919407Z","steps":["trace[802355918] 'process raft request' (duration: 211.71547ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:37.919454Z","caller":"traceutil/trace.go:171","msg":"trace[1977012586] transaction","detail":"{read_only:false; response_revision:7582; number_of_response:1; }","duration":"211.818101ms","start":"2026-02-26T01:56:37.707631Z","end":"2026-02-26T01:56:37.919449Z","steps":["trace[1977012586] 'process raft request' (duration: 211.726509ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:37.919422Z","caller":"traceutil/trace.go:171","msg":"trace[749132824] transaction","detail":"{read_only:false; response_revision:7579; number_of_response:1; }","duration":"211.932073ms","start":"2026-02-26T01:56:37.707478Z","end":"2026-02-26T01:56:37.91941Z","steps":["trace[749132824] 'process raft request' (duration: 211.597756ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:37.919531Z","caller":"traceutil/trace.go:171","msg":"trace[2074244727] transaction","detail":"{read_only:false; response_revision:7581; number_of_response:1; }","duration":"211.901533ms","start":"2026-02-26T01:56:37.707601Z","end":"2026-02-26T01:56:37.919503Z","steps":["trace[2074244727] 'process raft request' (duration: 211.73418ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:49.289696Z","caller":"traceutil/trace.go:171","msg":"trace[504816009] linearizableReadLoop","detail":"{readStateIndex:7919; appliedIndex:7918; }","duration":"115.851987ms","start":"2026-02-26T01:56:49.173816Z","end":"2026-02-26T01:56:49.289668Z","steps":["trace[504816009] 'read index received' (duration: 115.628053ms)","trace[504816009] 'applied index is now lower than readState.Index' (duration: 222.804µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:56:49.289759Z","caller":"traceutil/trace.go:171","msg":"trace[1667949628] transaction","detail":"{read_only:false; response_revision:7635; number_of_response:1; }","duration":"126.906297ms","start":"2026-02-26T01:56:49.162817Z","end":"2026-02-26T01:56:49.289724Z","steps":["trace[1667949628] 'process raft request' (duration: 126.706283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:49.289862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.027702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:56:49.289911Z","caller":"traceutil/trace.go:171","msg":"trace[364018972] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:7635; }","duration":"116.132893ms","start":"2026-02-26T01:56:49.173764Z","end":"2026-02-26T01:56:49.289897Z","steps":["trace[364018972] 'agreement among raft nodes before linearized reading' (duration: 116.040611ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:50.171552Z","caller":"traceutil/trace.go:171","msg":"trace[906999332] transaction","detail":"{read_only:false; response_revision:7639; number_of_response:1; }","duration":"157.747458ms","start":"2026-02-26T01:56:50.013769Z","end":"2026-02-26T01:56:50.171516Z","steps":["trace[906999332] 'process raft request' (duration: 157.519324ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:50.171569Z","caller":"traceutil/trace.go:171","msg":"trace[1140424256] transaction","detail":"{read_only:false; response_revision:7641; number_of_response:1; }","duration":"157.719387ms","start":"2026-02-26T01:56:50.013819Z","end":"2026-02-26T01:56:50.171538Z","steps":["trace[1140424256] 'process raft request' (duration: 157.667596ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:50.171608Z","caller":"traceutil/trace.go:171","msg":"trace[466187612] transaction","detail":"{read_only:false; response_revision:7640; number_of_response:1; }","duration":"157.793239ms","start":"2026-02-26T01:56:50.01377Z","end":"2026-02-26T01:56:50.171563Z","steps":["trace[466187612] 'process raft request' (duration: 157.677077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:50.499198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.723037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:56:50.499491Z","caller":"traceutil/trace.go:171","msg":"trace[2002501136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7641; }","duration":"101.040633ms","start":"2026-02-26T01:56:50.398432Z","end":"2026-02-26T01:56:50.499473Z","steps":["trace[2002501136] 'range keys from in-memory index tree' (duration: 100.619436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:51.569246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.273335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-02-26T01:56:51.569331Z","caller":"traceutil/trace.go:171","msg":"trace[67893591] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7643; }","duration":"125.419598ms","start":"2026-02-26T01:56:51.443893Z","end":"2026-02-26T01:56:51.569312Z","steps":["trace[67893591] 'range keys from in-memory index tree' (duration: 125.073621ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:51.944319Z","caller":"traceutil/trace.go:171","msg":"trace[506903227] transaction","detail":"{read_only:false; response_revision:7644; number_of_response:1; }","duration":"153.419633ms","start":"2026-02-26T01:56:51.790863Z","end":"2026-02-26T01:56:51.944283Z","steps":["trace[506903227] 'process raft request' (duration: 153.236269ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:14.540594Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5793} {"level":"warn","ts":"2026-02-26T01:57:14.703913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.024145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-mon\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-26T01:57:14.704009Z","caller":"traceutil/trace.go:171","msg":"trace[795919880] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-mon; range_end:; response_count:1; response_revision:7789; }","duration":"154.173347ms","start":"2026-02-26T01:57:14.549814Z","end":"2026-02-26T01:57:14.703987Z","steps":["trace[795919880] 'agreement among raft nodes before linearized reading' (duration: 74.866894ms)","trace[795919880] 'range keys from in-memory index tree' (duration: 79.096468ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:57:14.805491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.705618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:57:14.805655Z","caller":"traceutil/trace.go:171","msg":"trace[96421174] linearizableReadLoop","detail":"{readStateIndex:8081; appliedIndex:8080; }","duration":"181.088632ms","start":"2026-02-26T01:57:14.624542Z","end":"2026-02-26T01:57:14.805631Z","steps":["trace[96421174] 'read index received' (duration: 27.22546ms)","trace[96421174] 'applied index is now lower than readState.Index' (duration: 153.861282ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:57:14.805667Z","caller":"traceutil/trace.go:171","msg":"trace[1066403658] transaction","detail":"{read_only:false; response_revision:7790; number_of_response:1; }","duration":"246.943478ms","start":"2026-02-26T01:57:14.558679Z","end":"2026-02-26T01:57:14.805622Z","steps":["trace[1066403658] 'process raft request' (duration: 93.005635ms)","trace[1066403658] 'compare' (duration: 52.072382ms)","trace[1066403658] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/ceph.rook.io/cephclusters/openstack/ceph; req_size:5128; } (duration: 101.472183ms)"],"step_count":3} {"level":"warn","ts":"2026-02-26T01:57:14.809794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.742932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/rook-ceph/rook-ceph-operator-76bbbb77ff-6h5kk\" ","response":"range_response_count:1 size:4558"} {"level":"info","ts":"2026-02-26T01:57:14.809836Z","caller":"traceutil/trace.go:171","msg":"trace[897262382] range","detail":"{range_begin:/registry/pods/rook-ceph/rook-ceph-operator-76bbbb77ff-6h5kk; range_end:; response_count:1; response_revision:7790; }","duration":"257.816154ms","start":"2026-02-26T01:57:14.552007Z","end":"2026-02-26T01:57:14.809823Z","steps":["trace[897262382] 'agreement among raft nodes before linearized reading' (duration: 253.688852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:57:14.809875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.931955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" limit:10000 ","response":"range_response_count:6 size:42810"} {"level":"info","ts":"2026-02-26T01:57:14.809942Z","caller":"traceutil/trace.go:171","msg":"trace[1350752497] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:6; response_revision:7790; }","duration":"260.048787ms","start":"2026-02-26T01:57:14.549876Z","end":"2026-02-26T01:57:14.809925Z","steps":["trace[1350752497] 'agreement among raft nodes before linearized reading' (duration: 255.875264ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:14.810106Z","caller":"traceutil/trace.go:171","msg":"trace[1224532768] transaction","detail":"{read_only:false; response_revision:7791; number_of_response:1; }","duration":"177.720294ms","start":"2026-02-26T01:57:14.632377Z","end":"2026-02-26T01:57:14.810097Z","steps":["trace[1224532768] 'process raft request' (duration: 173.243285ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:14.810354Z","caller":"traceutil/trace.go:171","msg":"trace[1679544709] transaction","detail":"{read_only:false; response_revision:7794; number_of_response:1; }","duration":"101.444563ms","start":"2026-02-26T01:57:14.708899Z","end":"2026-02-26T01:57:14.810344Z","steps":["trace[1679544709] 'process raft request' (duration: 101.311369ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:14.810472Z","caller":"traceutil/trace.go:171","msg":"trace[2078035304] transaction","detail":"{read_only:false; response_revision:7792; number_of_response:1; }","duration":"175.075443ms","start":"2026-02-26T01:57:14.635389Z","end":"2026-02-26T01:57:14.810464Z","steps":["trace[2078035304] 'process raft request' (duration: 174.646824ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:14.810496Z","caller":"traceutil/trace.go:171","msg":"trace[1392243279] transaction","detail":"{read_only:false; response_revision:7793; number_of_response:1; }","duration":"169.057604ms","start":"2026-02-26T01:57:14.641381Z","end":"2026-02-26T01:57:14.810438Z","steps":["trace[1392243279] 'process raft request' (duration: 168.721436ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:14.854139Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5793,"took":"310.116211ms","hash":3863353945,"current-db-size-bytes":43053056,"current-db-size":"43 MB","current-db-size-in-use-bytes":23085056,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-26T01:57:14.854221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3863353945,"revision":5793,"compact-revision":2783} {"level":"warn","ts":"2026-02-26T01:57:15.879058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.634924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:57:15.879134Z","caller":"traceutil/trace.go:171","msg":"trace[1901992427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7816; }","duration":"107.746927ms","start":"2026-02-26T01:57:15.77137Z","end":"2026-02-26T01:57:15.879117Z","steps":["trace[1901992427] 'range keys from in-memory index tree' (duration: 107.567303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:57:15.879114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.08806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-csi-config\" ","response":"range_response_count:1 size:512"} {"level":"info","ts":"2026-02-26T01:57:15.879199Z","caller":"traceutil/trace.go:171","msg":"trace[822249030] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-csi-config; range_end:; response_count:1; response_revision:7816; }","duration":"129.228163ms","start":"2026-02-26T01:57:15.749953Z","end":"2026-02-26T01:57:15.879181Z","steps":["trace[822249030] 'range keys from in-memory index tree' (duration: 128.892346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:57:15.879226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.379348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-02-26T01:57:15.87929Z","caller":"traceutil/trace.go:171","msg":"trace[1334611902] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:7816; }","duration":"122.429459ms","start":"2026-02-26T01:57:15.756839Z","end":"2026-02-26T01:57:15.879269Z","steps":["trace[1334611902] 'range keys from in-memory index tree' (duration: 122.210864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:57:15.879117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.005962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:122498"} {"level":"info","ts":"2026-02-26T01:57:15.879441Z","caller":"traceutil/trace.go:171","msg":"trace[287468867] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:7816; }","duration":"109.346959ms","start":"2026-02-26T01:57:15.770044Z","end":"2026-02-26T01:57:15.879391Z","steps":["trace[287468867] 'range keys from in-memory index tree' (duration: 108.766637ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:19.761106Z","caller":"traceutil/trace.go:171","msg":"trace[758068915] transaction","detail":"{read_only:false; response_revision:7836; number_of_response:1; }","duration":"341.580514ms","start":"2026-02-26T01:57:19.419489Z","end":"2026-02-26T01:57:19.76107Z","steps":["trace[758068915] 'process raft request' (duration: 341.33504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:57:19.761339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:57:19.419468Z","time spent":"341.742558ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:57:22.404461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.125804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:57:22.404569Z","caller":"traceutil/trace.go:171","msg":"trace[843077493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7846; }","duration":"108.312438ms","start":"2026-02-26T01:57:22.296234Z","end":"2026-02-26T01:57:22.404546Z","steps":["trace[843077493] 'range keys from in-memory index tree' (duration: 107.852878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:57:23.209283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.384066ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-26T01:57:23.210212Z","caller":"traceutil/trace.go:171","msg":"trace[1017824182] transaction","detail":"{read_only:false; response_revision:7852; number_of_response:1; }","duration":"365.214353ms","start":"2026-02-26T01:57:22.844961Z","end":"2026-02-26T01:57:23.210175Z","steps":["trace[1017824182] 'process raft request' (duration: 364.449428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:57:23.21039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:57:22.844932Z","time spent":"365.356485ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:57:32.321822Z","caller":"traceutil/trace.go:171","msg":"trace[1709836009] transaction","detail":"{read_only:false; response_revision:7938; number_of_response:1; }","duration":"114.276806ms","start":"2026-02-26T01:57:32.207523Z","end":"2026-02-26T01:57:32.3218Z","steps":["trace[1709836009] 'process raft request' (duration: 113.957729ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:32.440367Z","caller":"traceutil/trace.go:171","msg":"trace[813098147] transaction","detail":"{read_only:false; response_revision:7939; number_of_response:1; }","duration":"114.506221ms","start":"2026-02-26T01:57:32.325847Z","end":"2026-02-26T01:57:32.440353Z","steps":["trace[813098147] 'process raft request' (duration: 77.247493ms)","trace[813098147] 'compare' (duration: 36.993233ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:57:32.440571Z","caller":"traceutil/trace.go:171","msg":"trace[387569509] linearizableReadLoop","detail":"{readStateIndex:8236; appliedIndex:8235; }","duration":"114.205225ms","start":"2026-02-26T01:57:32.325948Z","end":"2026-02-26T01:57:32.440153Z","steps":["trace[387569509] 'read index received' (duration: 77.13862ms)","trace[387569509] 'applied index is now lower than readState.Index' (duration: 37.065925ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:57:32.440898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.935409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228746"} {"level":"info","ts":"2026-02-26T01:57:32.440926Z","caller":"traceutil/trace.go:171","msg":"trace[1026165591] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:7939; }","duration":"114.99431ms","start":"2026-02-26T01:57:32.325926Z","end":"2026-02-26T01:57:32.44092Z","steps":["trace[1026165591] 'agreement among raft nodes before linearized reading' (duration: 114.237815ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:33.269036Z","caller":"traceutil/trace.go:171","msg":"trace[1040003016] transaction","detail":"{read_only:false; response_revision:7947; number_of_response:1; }","duration":"199.203331ms","start":"2026-02-26T01:57:33.069805Z","end":"2026-02-26T01:57:33.269008Z","steps":["trace[1040003016] 'process raft request' (duration: 199.045538ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:45.988936Z","caller":"traceutil/trace.go:171","msg":"trace[1338122797] transaction","detail":"{read_only:false; response_revision:8043; number_of_response:1; }","duration":"126.490139ms","start":"2026-02-26T01:57:45.862421Z","end":"2026-02-26T01:57:45.988911Z","steps":["trace[1338122797] 'process raft request' (duration: 125.460139ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:45.989115Z","caller":"traceutil/trace.go:171","msg":"trace[958186396] transaction","detail":"{read_only:false; response_revision:8044; number_of_response:1; }","duration":"104.748077ms","start":"2026-02-26T01:57:45.884339Z","end":"2026-02-26T01:57:45.989087Z","steps":["trace[958186396] 'process raft request' (duration: 104.525203ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:58:49.422487Z","caller":"traceutil/trace.go:171","msg":"trace[1860006429] linearizableReadLoop","detail":"{readStateIndex:8972; appliedIndex:8970; }","duration":"541.097817ms","start":"2026-02-26T01:58:48.881356Z","end":"2026-02-26T01:58:49.422453Z","steps":["trace[1860006429] 'read index received' (duration: 31.344042ms)","trace[1860006429] 'applied index is now lower than readState.Index' (duration: 509.752185ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:58:49.422568Z","caller":"traceutil/trace.go:171","msg":"trace[218070034] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"578.391967ms","start":"2026-02-26T01:58:48.844132Z","end":"2026-02-26T01:58:49.422524Z","steps":["trace[218070034] 'process raft request' (duration: 578.138862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:49.422745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:58:48.844113Z","time spent":"578.52995ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:58:49.422781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.403824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ttfwz\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-02-26T01:58:49.422798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.966308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T01:58:49.422832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.53954ms","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-26T01:58:49.422906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.204744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:58:49.422905Z","caller":"traceutil/trace.go:171","msg":"trace[1655302352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8651; }","duration":"511.641153ms","start":"2026-02-26T01:58:48.911246Z","end":"2026-02-26T01:58:49.422887Z","steps":["trace[1655302352] 'agreement among raft nodes before linearized reading' (duration: 511.407868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:49.422921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.487375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:58:49.422931Z","caller":"traceutil/trace.go:171","msg":"trace[1731347662] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:8651; }","duration":"446.267755ms","start":"2026-02-26T01:58:48.976658Z","end":"2026-02-26T01:58:49.422925Z","steps":["trace[1731347662] 'agreement among raft nodes before linearized reading' (duration: 446.222315ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:58:49.42296Z","caller":"traceutil/trace.go:171","msg":"trace[243857499] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8651; }","duration":"350.553906ms","start":"2026-02-26T01:58:49.072397Z","end":"2026-02-26T01:58:49.422951Z","steps":["trace[243857499] 'agreement among raft nodes before linearized reading' (duration: 350.489465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:49.422999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.250721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-02-26T01:58:49.423064Z","caller":"traceutil/trace.go:171","msg":"trace[1800634102] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8651; }","duration":"407.336563ms","start":"2026-02-26T01:58:49.015711Z","end":"2026-02-26T01:58:49.423048Z","steps":["trace[1800634102] 'agreement among raft nodes before linearized reading' (duration: 407.1722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:49.422959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:58:48.911236Z","time spent":"511.709834ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:58:49.423112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:58:49.015699Z","time spent":"407.401384ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-02-26T01:58:49.423011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:58:49.072382Z","time spent":"350.620508ms","remote":"127.0.0.1:37894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:58:49.422858Z","caller":"traceutil/trace.go:171","msg":"trace[1055499790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8651; }","duration":"398.041199ms","start":"2026-02-26T01:58:49.024801Z","end":"2026-02-26T01:58:49.422842Z","steps":["trace[1055499790] 'agreement among raft nodes before linearized reading' (duration: 397.870755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:49.423259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:58:49.024793Z","time spent":"398.453307ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:58:49.422967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:58:48.976641Z","time spent":"446.319776ms","remote":"127.0.0.1:38050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":31,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:58:49.422836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.98151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-26T01:58:49.423394Z","caller":"traceutil/trace.go:171","msg":"trace[176062925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8651; }","duration":"444.576851ms","start":"2026-02-26T01:58:48.978806Z","end":"2026-02-26T01:58:49.423383Z","steps":["trace[176062925] 'agreement among raft nodes before linearized reading' (duration: 443.889228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:49.423439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:58:48.97879Z","time spent":"444.637082ms","remote":"127.0.0.1:38040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T01:58:49.422849Z","caller":"traceutil/trace.go:171","msg":"trace[847241658] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ttfwz; range_end:; response_count:1; response_revision:8651; }","duration":"541.530056ms","start":"2026-02-26T01:58:48.881299Z","end":"2026-02-26T01:58:49.422829Z","steps":["trace[847241658] 'agreement among raft nodes before linearized reading' (duration: 541.299221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:49.423569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:58:48.88128Z","time spent":"542.264301ms","remote":"127.0.0.1:37948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-ttfwz\" "} {"level":"info","ts":"2026-02-26T01:58:49.608985Z","caller":"traceutil/trace.go:171","msg":"trace[10314597] linearizableReadLoop","detail":"{readStateIndex:8973; appliedIndex:8972; }","duration":"181.293618ms","start":"2026-02-26T01:58:49.42767Z","end":"2026-02-26T01:58:49.608964Z","steps":["trace[10314597] 'read index received' (duration: 177.202806ms)","trace[10314597] 'applied index is now lower than readState.Index' (duration: 4.089702ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:58:49.609108Z","caller":"traceutil/trace.go:171","msg":"trace[193950680] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"182.170285ms","start":"2026-02-26T01:58:49.426904Z","end":"2026-02-26T01:58:49.609074Z","steps":["trace[193950680] 'process raft request' (duration: 177.956881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:49.609253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.508742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19405"} {"level":"info","ts":"2026-02-26T01:58:49.609293Z","caller":"traceutil/trace.go:171","msg":"trace[126723856] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8652; }","duration":"181.627294ms","start":"2026-02-26T01:58:49.427655Z","end":"2026-02-26T01:58:49.609282Z","steps":["trace[126723856] 'agreement among raft nodes before linearized reading' (duration: 181.41181ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:58:49.621033Z","caller":"traceutil/trace.go:171","msg":"trace[1535390837] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"193.302016ms","start":"2026-02-26T01:58:49.427708Z","end":"2026-02-26T01:58:49.62101Z","steps":["trace[1535390837] 'process raft request' (duration: 192.972989ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:58:49.621132Z","caller":"traceutil/trace.go:171","msg":"trace[877469916] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"193.157273ms","start":"2026-02-26T01:58:49.427923Z","end":"2026-02-26T01:58:49.62108Z","steps":["trace[877469916] 'process raft request' (duration: 192.99657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:49.621685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.738694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291507"} {"level":"info","ts":"2026-02-26T01:58:49.621785Z","caller":"traceutil/trace.go:171","msg":"trace[1287995260] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:8655; }","duration":"193.867137ms","start":"2026-02-26T01:58:49.427895Z","end":"2026-02-26T01:58:49.621762Z","steps":["trace[1287995260] 'agreement among raft nodes before linearized reading' (duration: 193.139843ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T02:01:28.490482Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-26T02:01:28.496088Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-26T02:01:28.496211Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-26T02:02:14.547384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7786} {"level":"info","ts":"2026-02-26T02:02:14.626832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7786,"took":"76.160893ms","hash":582329340,"current-db-size-bytes":43053056,"current-db-size":"43 MB","current-db-size-in-use-bytes":24862720,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-02-26T02:02:14.626924Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":582329340,"revision":7786,"compact-revision":5793}