{"level":"warn","ts":"2026-02-25T15:03:30.42275Z","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-25T15:03:30.422854Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-25T15:03:30.422924Z","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-25T15:03:30.42294Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-02-25T15:03:30.422958Z","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-25T15:03:30.423617Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-02-25T15:03:30.42369Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-25T15:03:30.427686Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.769547ms"} {"level":"info","ts":"2026-02-25T15:03:30.431839Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-02-25T15:03:30.43191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T15:03:30.431933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-02-25T15:03:30.431947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T15:03:30.431956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-02-25T15:03:30.431994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-02-25T15:03:30.436878Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T15:03:30.438188Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T15:03:30.439335Z","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-25T15:03:30.444949Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T15:03:30.445206Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T15:03:30.445256Z","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-25T15:03:30.445328Z","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-25T15:03:30.445357Z","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-25T15:03:30.445994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-02-25T15:03:30.446584Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-02-25T15:03:30.449799Z","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-25T15:03:30.449916Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-02-25T15:03:30.449985Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-02-25T15:03:30.450264Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T15:03:30.450327Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T15:03:30.53218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T15:03:30.532244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T15:03:30.532285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-02-25T15:03:30.532309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-02-25T15:03:30.532323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-02-25T15:03:30.532343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-02-25T15:03:30.53236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-02-25T15:03:30.533275Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T15:03:30.533978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T15:03:30.533979Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T15:03:30.53412Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T15:03:30.534512Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T15:03:30.534685Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T15:03:30.534725Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T15:03:30.534877Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T15:03:30.535087Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T15:03:30.537307Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T15:03:30.538118Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-02-25T15:03:34.903844Z","caller":"traceutil/trace.go:171","msg":"trace[866807685] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"103.933138ms","start":"2026-02-25T15:03:34.799877Z","end":"2026-02-25T15:03:34.90381Z","steps":["trace[866807685] 'process raft request' (duration: 103.759583ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:03:34.948946Z","caller":"traceutil/trace.go:171","msg":"trace[1756262825] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"113.757609ms","start":"2026-02-25T15:03:34.835164Z","end":"2026-02-25T15:03:34.948922Z","steps":["trace[1756262825] 'process raft request' (duration: 113.623285ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:03:36.193065Z","caller":"traceutil/trace.go:171","msg":"trace[1068282597] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"100.242993ms","start":"2026-02-25T15:03:36.092783Z","end":"2026-02-25T15:03:36.193026Z","steps":["trace[1068282597] 'read index received' (duration: 30.159801ms)","trace[1068282597] 'applied index is now lower than readState.Index' (duration: 70.081622ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:03:36.193086Z","caller":"traceutil/trace.go:171","msg":"trace[1724214854] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"100.668194ms","start":"2026-02-25T15:03:36.092374Z","end":"2026-02-25T15:03:36.193042Z","steps":["trace[1724214854] 'process raft request' (duration: 30.657044ms)","trace[1724214854] 'compare' (duration: 69.819555ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:03:36.193321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.380807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:03:36.193416Z","caller":"traceutil/trace.go:171","msg":"trace[1239640805] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:281; }","duration":"100.530452ms","start":"2026-02-25T15:03:36.092871Z","end":"2026-02-25T15:03:36.193401Z","steps":["trace[1239640805] 'agreement among raft nodes before linearized reading' (duration: 100.373388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:03:36.193329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.50203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-02-25T15:03:36.193968Z","caller":"traceutil/trace.go:171","msg":"trace[603821784] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:281; }","duration":"101.102968ms","start":"2026-02-25T15:03:36.092739Z","end":"2026-02-25T15:03:36.193842Z","steps":["trace[603821784] 'agreement among raft nodes before linearized reading' (duration: 100.385967ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:03:36.380012Z","caller":"traceutil/trace.go:171","msg":"trace[374298762] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"104.887065ms","start":"2026-02-25T15:03:36.275084Z","end":"2026-02-25T15:03:36.379971Z","steps":["trace[374298762] 'process raft request' (duration: 73.658854ms)","trace[374298762] 'compare' (duration: 30.751798ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:03:36.690468Z","caller":"traceutil/trace.go:171","msg":"trace[537649131] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"104.385912ms","start":"2026-02-25T15:03:36.586053Z","end":"2026-02-25T15:03:36.690439Z","steps":["trace[537649131] 'read index received' (duration: 91.590816ms)","trace[537649131] 'applied index is now lower than readState.Index' (duration: 12.794125ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:03:36.690681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.613728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:03:36.690774Z","caller":"traceutil/trace.go:171","msg":"trace[1039111524] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:307; }","duration":"104.723291ms","start":"2026-02-25T15:03:36.586031Z","end":"2026-02-25T15:03:36.690754Z","steps":["trace[1039111524] 'agreement among raft nodes before linearized reading' (duration: 104.545286ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:03:36.690514Z","caller":"traceutil/trace.go:171","msg":"trace[1191128358] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"132.606688ms","start":"2026-02-25T15:03:36.557841Z","end":"2026-02-25T15:03:36.690447Z","steps":["trace[1191128358] 'process raft request' (duration: 119.807582ms)","trace[1191128358] 'compare' (duration: 12.439015ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:03:46.646818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.499555ms","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-25T15:03:46.646941Z","caller":"traceutil/trace.go:171","msg":"trace[481219717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:402; }","duration":"111.67362ms","start":"2026-02-25T15:03:46.535244Z","end":"2026-02-25T15:03:46.646917Z","steps":["trace[481219717] 'range keys from in-memory index tree' (duration: 111.3313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:03:46.647017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.625281ms","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-25T15:03:46.64712Z","caller":"traceutil/trace.go:171","msg":"trace[1529012490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:402; }","duration":"193.758474ms","start":"2026-02-25T15:03:46.453339Z","end":"2026-02-25T15:03:46.647097Z","steps":["trace[1529012490] 'range keys from in-memory index tree' (duration: 193.536868ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:03:57.618844Z","caller":"traceutil/trace.go:171","msg":"trace[1665769334] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"413.910343ms","start":"2026-02-25T15:03:57.204909Z","end":"2026-02-25T15:03:57.61882Z","steps":["trace[1665769334] 'process raft request' (duration: 413.774378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:03:57.619471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:03:57.204893Z","time spent":"414.041537ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:03:57.629567Z","caller":"traceutil/trace.go:171","msg":"trace[715103369] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:475; }","duration":"165.992652ms","start":"2026-02-25T15:03:57.463544Z","end":"2026-02-25T15:03:57.629537Z","steps":["trace[715103369] 'read index received' (duration: 155.251154ms)","trace[715103369] 'applied index is now lower than readState.Index' (duration: 10.739657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:03:57.629763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.198147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12661"} {"level":"info","ts":"2026-02-25T15:03:57.629741Z","caller":"traceutil/trace.go:171","msg":"trace[559209425] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"424.704531ms","start":"2026-02-25T15:03:57.204979Z","end":"2026-02-25T15:03:57.629684Z","steps":["trace[559209425] 'process raft request' (duration: 424.400412ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:03:57.629834Z","caller":"traceutil/trace.go:171","msg":"trace[1283441861] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:461; }","duration":"166.33235ms","start":"2026-02-25T15:03:57.463486Z","end":"2026-02-25T15:03:57.629818Z","steps":["trace[1283441861] 'agreement among raft nodes before linearized reading' (duration: 166.162576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:03:57.630094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:03:57.204955Z","time spent":"424.991159ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:03:59.252282Z","caller":"traceutil/trace.go:171","msg":"trace[453451153] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"134.010468ms","start":"2026-02-25T15:03:59.118242Z","end":"2026-02-25T15:03:59.252252Z","steps":["trace[453451153] 'process raft request' (duration: 97.246487ms)","trace[453451153] 'compare' (duration: 36.529244ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:03:59.252252Z","caller":"traceutil/trace.go:171","msg":"trace[1352736546] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"126.0545ms","start":"2026-02-25T15:03:59.126161Z","end":"2026-02-25T15:03:59.252215Z","steps":["trace[1352736546] 'read index received' (duration: 89.253628ms)","trace[1352736546] 'applied index is now lower than readState.Index' (duration: 36.799562ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:03:59.2526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.140395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lkvl2\" ","response":"range_response_count:1 size:14921"} {"level":"info","ts":"2026-02-25T15:03:59.252576Z","caller":"traceutil/trace.go:171","msg":"trace[1160100656] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"118.881915ms","start":"2026-02-25T15:03:59.133638Z","end":"2026-02-25T15:03:59.25252Z","steps":["trace[1160100656] 'process raft request' (duration: 118.612248ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:03:59.252668Z","caller":"traceutil/trace.go:171","msg":"trace[84791280] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lkvl2; range_end:; response_count:1; response_revision:490; }","duration":"132.279428ms","start":"2026-02-25T15:03:59.12037Z","end":"2026-02-25T15:03:59.252649Z","steps":["trace[84791280] 'agreement among raft nodes before linearized reading' (duration: 131.99691ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:03:59.252817Z","caller":"traceutil/trace.go:171","msg":"trace[278495344] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"119.0298ms","start":"2026-02-25T15:03:59.13376Z","end":"2026-02-25T15:03:59.25279Z","steps":["trace[278495344] 'process raft request' (duration: 118.578677ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:03:59.253794Z","caller":"traceutil/trace.go:171","msg":"trace[1687696972] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"101.245312ms","start":"2026-02-25T15:03:59.152528Z","end":"2026-02-25T15:03:59.253774Z","steps":["trace[1687696972] 'process raft request' (duration: 100.567663ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:03:59.424819Z","caller":"traceutil/trace.go:171","msg":"trace[601308079] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"163.084728ms","start":"2026-02-25T15:03:59.261712Z","end":"2026-02-25T15:03:59.424797Z","steps":["trace[601308079] 'process raft request' (duration: 104.69191ms)","trace[601308079] 'compare' (duration: 58.195622ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:03:59.425122Z","caller":"traceutil/trace.go:171","msg":"trace[2070954161] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"155.043699ms","start":"2026-02-25T15:03:59.270069Z","end":"2026-02-25T15:03:59.425113Z","steps":["trace[2070954161] 'process raft request' (duration: 154.663578ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:03:59.425086Z","caller":"traceutil/trace.go:171","msg":"trace[169571846] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"161.779772ms","start":"2026-02-25T15:03:59.263269Z","end":"2026-02-25T15:03:59.425049Z","steps":["trace[169571846] 'read index received' (duration: 69.420574ms)","trace[169571846] 'applied index is now lower than readState.Index' (duration: 92.356537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:03:59.425253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.963536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-25T15:03:59.425315Z","caller":"traceutil/trace.go:171","msg":"trace[1645678151] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:494; }","duration":"162.08902ms","start":"2026-02-25T15:03:59.26321Z","end":"2026-02-25T15:03:59.425299Z","steps":["trace[1645678151] 'agreement among raft nodes before linearized reading' (duration: 161.937806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:03:59.425383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.965799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-25T15:03:59.425417Z","caller":"traceutil/trace.go:171","msg":"trace[1787813881] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:494; }","duration":"160.02285ms","start":"2026-02-25T15:03:59.265384Z","end":"2026-02-25T15:03:59.425407Z","steps":["trace[1787813881] 'agreement among raft nodes before linearized reading' (duration: 159.847216ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:04:07.385307Z","caller":"traceutil/trace.go:171","msg":"trace[1578794514] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"162.243822ms","start":"2026-02-25T15:04:07.223025Z","end":"2026-02-25T15:04:07.385269Z","steps":["trace[1578794514] 'process raft request' (duration: 162.017465ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:04:07.822796Z","caller":"traceutil/trace.go:171","msg":"trace[275148360] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"135.915026ms","start":"2026-02-25T15:04:07.686847Z","end":"2026-02-25T15:04:07.822762Z","steps":["trace[275148360] 'process raft request' (duration: 135.733451ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:04:08.35168Z","caller":"traceutil/trace.go:171","msg":"trace[1797853859] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"664.119848ms","start":"2026-02-25T15:04:07.687529Z","end":"2026-02-25T15:04:08.351648Z","steps":["trace[1797853859] 'process raft request' (duration: 633.662393ms)","trace[1797853859] 'compare' (duration: 30.003343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:04:08.351859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:04:07.687505Z","time spent":"664.265231ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:04:08.973351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.095919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:04:08.973575Z","caller":"traceutil/trace.go:171","msg":"trace[1130059062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"325.400556ms","start":"2026-02-25T15:04:08.648146Z","end":"2026-02-25T15:04:08.973547Z","steps":["trace[1130059062] 'range keys from in-memory index tree' (duration: 324.879063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:04:08.973542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.559123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T15:04:08.973503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.696506ms","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-25T15:04:08.973727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.25956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:04:08.97375Z","caller":"traceutil/trace.go:171","msg":"trace[1430651864] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:546; }","duration":"502.852992ms","start":"2026-02-25T15:04:08.470847Z","end":"2026-02-25T15:04:08.9737Z","steps":["trace[1430651864] 'range keys from in-memory index tree' (duration: 502.43052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:04:08.973685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:04:08.648113Z","time spent":"325.554801ms","remote":"127.0.0.1:39236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T15:04:08.973616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.419024ms","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-25T15:04:08.973947Z","caller":"traceutil/trace.go:171","msg":"trace[1651604781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"140.743043ms","start":"2026-02-25T15:04:08.833182Z","end":"2026-02-25T15:04:08.973925Z","steps":["trace[1651604781] 'range keys from in-memory index tree' (duration: 140.28834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:04:08.973957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:04:08.470816Z","time spent":"502.975425ms","remote":"127.0.0.1:39334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-02-25T15:04:08.9738Z","caller":"traceutil/trace.go:171","msg":"trace[958364832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"422.394413ms","start":"2026-02-25T15:04:08.551386Z","end":"2026-02-25T15:04:08.97378Z","steps":["trace[958364832] 'range keys from in-memory index tree' (duration: 422.092545ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:04:08.973798Z","caller":"traceutil/trace.go:171","msg":"trace[808478455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:546; }","duration":"145.023425ms","start":"2026-02-25T15:04:08.82875Z","end":"2026-02-25T15:04:08.973774Z","steps":["trace[808478455] 'range keys from in-memory index tree' (duration: 144.506902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:04:08.974089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:04:08.551362Z","time spent":"422.709382ms","remote":"127.0.0.1:39230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T15:04:09.527331Z","caller":"traceutil/trace.go:171","msg":"trace[89183774] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"546.139737ms","start":"2026-02-25T15:04:08.981158Z","end":"2026-02-25T15:04:09.527297Z","steps":["trace[89183774] 'process raft request' (duration: 511.545672ms)","trace[89183774] 'compare' (duration: 34.295217ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:04:09.5276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:04:08.981138Z","time spent":"546.355874ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:04:09.529906Z","caller":"traceutil/trace.go:171","msg":"trace[1715879148] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:564; }","duration":"544.432572ms","start":"2026-02-25T15:04:08.985436Z","end":"2026-02-25T15:04:09.529868Z","steps":["trace[1715879148] 'read index received' (duration: 60.569293ms)","trace[1715879148] 'applied index is now lower than readState.Index' (duration: 483.862299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:04:09.530009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.557325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:04:09.53004Z","caller":"traceutil/trace.go:171","msg":"trace[292697731] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:548; }","duration":"544.632157ms","start":"2026-02-25T15:04:08.985397Z","end":"2026-02-25T15:04:09.53003Z","steps":["trace[292697731] 'agreement among raft nodes before linearized reading' (duration: 544.561585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:04:09.530064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:04:08.985383Z","time spent":"544.675218ms","remote":"127.0.0.1:39334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-02-25T15:04:10.423254Z","caller":"traceutil/trace.go:171","msg":"trace[460132599] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"105.954502ms","start":"2026-02-25T15:04:10.317273Z","end":"2026-02-25T15:04:10.423228Z","steps":["trace[460132599] 'process raft request' (duration: 105.890351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:04:10.423342Z","caller":"traceutil/trace.go:171","msg":"trace[2078686527] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"109.296241ms","start":"2026-02-25T15:04:10.314008Z","end":"2026-02-25T15:04:10.423304Z","steps":["trace[2078686527] 'process raft request' (duration: 102.090351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:04:10.423792Z","caller":"traceutil/trace.go:171","msg":"trace[830575495] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"100.284333ms","start":"2026-02-25T15:04:10.323481Z","end":"2026-02-25T15:04:10.423765Z","steps":["trace[830575495] 'process raft request' (duration: 100.143069ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:04:10.423848Z","caller":"traceutil/trace.go:171","msg":"trace[2035835536] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"101.74991ms","start":"2026-02-25T15:04:10.322088Z","end":"2026-02-25T15:04:10.423837Z","steps":["trace[2035835536] 'process raft request' (duration: 101.435862ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:04:11.562045Z","caller":"traceutil/trace.go:171","msg":"trace[679353093] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"122.894151ms","start":"2026-02-25T15:04:11.439123Z","end":"2026-02-25T15:04:11.562017Z","steps":["trace[679353093] 'process raft request' (duration: 122.693535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:04:15.304448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.260994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:04:15.304633Z","caller":"traceutil/trace.go:171","msg":"trace[1139154168] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"459.157155ms","start":"2026-02-25T15:04:14.845441Z","end":"2026-02-25T15:04:15.304598Z","steps":["trace[1139154168] 'process raft request' (duration: 271.507791ms)","trace[1139154168] 'compare' (duration: 186.926875ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:04:15.30468Z","caller":"traceutil/trace.go:171","msg":"trace[1440777635] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"267.728603ms","start":"2026-02-25T15:04:15.036919Z","end":"2026-02-25T15:04:15.304647Z","steps":["trace[1440777635] 'read index received' (duration: 79.833952ms)","trace[1440777635] 'applied index is now lower than readState.Index' (duration: 187.891541ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:04:15.304834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:04:14.845419Z","time spent":"459.31232ms","remote":"127.0.0.1:39286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:04:15.304972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.042271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-25T15:04:15.305016Z","caller":"traceutil/trace.go:171","msg":"trace[2130857601] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"268.124313ms","start":"2026-02-25T15:04:15.036879Z","end":"2026-02-25T15:04:15.305003Z","steps":["trace[2130857601] 'agreement among raft nodes before linearized reading' (duration: 267.921838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:04:15.305115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.582422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T15:04:15.305214Z","caller":"traceutil/trace.go:171","msg":"trace[2044907159] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:746; }","duration":"252.749156ms","start":"2026-02-25T15:04:15.052445Z","end":"2026-02-25T15:04:15.305195Z","steps":["trace[2044907159] 'agreement among raft nodes before linearized reading' (duration: 252.462689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:04:15.305224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.98805ms","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-25T15:04:15.305296Z","caller":"traceutil/trace.go:171","msg":"trace[2020463504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"231.094113ms","start":"2026-02-25T15:04:15.074186Z","end":"2026-02-25T15:04:15.30528Z","steps":["trace[2020463504] 'agreement among raft nodes before linearized reading' (duration: 230.860457ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:04:15.480279Z","caller":"traceutil/trace.go:171","msg":"trace[881729793] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"162.621431ms","start":"2026-02-25T15:04:15.31764Z","end":"2026-02-25T15:04:15.480262Z","steps":["trace[881729793] 'process raft request' (duration: 162.476548ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:04:15.480249Z","caller":"traceutil/trace.go:171","msg":"trace[1554508775] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"168.831186ms","start":"2026-02-25T15:04:15.311381Z","end":"2026-02-25T15:04:15.480212Z","steps":["trace[1554508775] 'process raft request' (duration: 155.031912ms)","trace[1554508775] 'compare' (duration: 13.551398ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:04:15.787154Z","caller":"traceutil/trace.go:171","msg":"trace[764810810] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"100.061947ms","start":"2026-02-25T15:04:15.68707Z","end":"2026-02-25T15:04:15.787132Z","steps":["trace[764810810] 'process raft request' (duration: 99.922503ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:04:30.890967Z","caller":"traceutil/trace.go:171","msg":"trace[509439863] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"365.807686ms","start":"2026-02-25T15:04:30.525131Z","end":"2026-02-25T15:04:30.890939Z","steps":["trace[509439863] 'process raft request' (duration: 365.6331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:04:30.891156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:04:30.52511Z","time spent":"365.96104ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:05:20.134552Z","caller":"traceutil/trace.go:171","msg":"trace[1847419793] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"100.088265ms","start":"2026-02-25T15:05:20.034434Z","end":"2026-02-25T15:05:20.134522Z","steps":["trace[1847419793] 'process raft request' (duration: 99.797748ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:05:20.305449Z","caller":"traceutil/trace.go:171","msg":"trace[807670979] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"167.602755ms","start":"2026-02-25T15:05:20.137822Z","end":"2026-02-25T15:05:20.305425Z","steps":["trace[807670979] 'process raft request' (duration: 166.757085ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:05:27.003316Z","caller":"traceutil/trace.go:171","msg":"trace[1304581867] linearizableReadLoop","detail":"{readStateIndex:1668; appliedIndex:1667; }","duration":"285.839379ms","start":"2026-02-25T15:05:26.717439Z","end":"2026-02-25T15:05:27.003278Z","steps":["trace[1304581867] 'read index received' (duration: 285.4235ms)","trace[1304581867] 'applied index is now lower than readState.Index' (duration: 414.189µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:05:27.003396Z","caller":"traceutil/trace.go:171","msg":"trace[762106225] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"351.509409ms","start":"2026-02-25T15:05:26.651835Z","end":"2026-02-25T15:05:27.003345Z","steps":["trace[762106225] 'process raft request' (duration: 351.033747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:05:27.003606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:05:26.651813Z","time spent":"351.685002ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3663,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:05:27.003664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.196699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5324"} {"level":"info","ts":"2026-02-25T15:05:27.004041Z","caller":"traceutil/trace.go:171","msg":"trace[288105626] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1607; }","duration":"286.642108ms","start":"2026-02-25T15:05:26.717367Z","end":"2026-02-25T15:05:27.004009Z","steps":["trace[288105626] 'agreement among raft nodes before linearized reading' (duration: 286.089146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:05:27.319092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.767251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:05:27.319249Z","caller":"traceutil/trace.go:171","msg":"trace[1752605152] linearizableReadLoop","detail":"{readStateIndex:1669; appliedIndex:1668; }","duration":"248.096235ms","start":"2026-02-25T15:05:27.07113Z","end":"2026-02-25T15:05:27.319226Z","steps":["trace[1752605152] 'read index received' (duration: 7.081308ms)","trace[1752605152] 'applied index is now lower than readState.Index' (duration: 241.013437ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:05:27.319437Z","caller":"traceutil/trace.go:171","msg":"trace[2022604499] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"523.551848ms","start":"2026-02-25T15:05:26.795827Z","end":"2026-02-25T15:05:27.319379Z","steps":["trace[2022604499] 'process raft request' (duration: 282.407278ms)","trace[2022604499] 'compare' (duration: 240.612257ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:05:27.31962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:05:26.7958Z","time spent":"523.724101ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:05:27.319635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.348158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T15:05:27.319662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.609093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T15:05:27.319723Z","caller":"traceutil/trace.go:171","msg":"trace[2016994105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1608; }","duration":"137.695095ms","start":"2026-02-25T15:05:27.182009Z","end":"2026-02-25T15:05:27.319704Z","steps":["trace[2016994105] 'agreement among raft nodes before linearized reading' (duration: 137.508651ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:05:27.319682Z","caller":"traceutil/trace.go:171","msg":"trace[1516311148] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1608; }","duration":"140.514242ms","start":"2026-02-25T15:05:27.179155Z","end":"2026-02-25T15:05:27.319669Z","steps":["trace[1516311148] 'agreement among raft nodes before linearized reading' (duration: 140.353308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:05:27.319752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.584746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-270cb956-70f2-4fa3-9f7a-8bac8a926f41\" ","response":"range_response_count:1 size:3678"} {"level":"info","ts":"2026-02-25T15:05:27.319868Z","caller":"traceutil/trace.go:171","msg":"trace[162067994] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-270cb956-70f2-4fa3-9f7a-8bac8a926f41; range_end:; response_count:1; response_revision:1608; }","duration":"248.761241ms","start":"2026-02-25T15:05:27.071075Z","end":"2026-02-25T15:05:27.319837Z","steps":["trace[162067994] 'agreement among raft nodes before linearized reading' (duration: 248.415292ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:05:41.461576Z","caller":"traceutil/trace.go:171","msg":"trace[299624484] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"264.321928ms","start":"2026-02-25T15:05:41.197222Z","end":"2026-02-25T15:05:41.461544Z","steps":["trace[299624484] 'process raft request' (duration: 264.103723ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:05:42.722147Z","caller":"traceutil/trace.go:171","msg":"trace[1644258200] linearizableReadLoop","detail":"{readStateIndex:1806; appliedIndex:1805; }","duration":"301.90505ms","start":"2026-02-25T15:05:42.420214Z","end":"2026-02-25T15:05:42.72212Z","steps":["trace[1644258200] 'read index received' (duration: 301.762467ms)","trace[1644258200] 'applied index is now lower than readState.Index' (duration: 141.113µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:05:42.722228Z","caller":"traceutil/trace.go:171","msg":"trace[628531087] transaction","detail":"{read_only:false; response_revision:1742; number_of_response:1; }","duration":"366.711098ms","start":"2026-02-25T15:05:42.355484Z","end":"2026-02-25T15:05:42.722195Z","steps":["trace[628531087] 'process raft request' (duration: 366.453762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:05:42.722399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:05:42.355457Z","time spent":"366.848881ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:05:42.722448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.214947ms","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-25T15:05:42.722542Z","caller":"traceutil/trace.go:171","msg":"trace[2062777266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1742; }","duration":"302.34113ms","start":"2026-02-25T15:05:42.42017Z","end":"2026-02-25T15:05:42.722511Z","steps":["trace[2062777266] 'agreement among raft nodes before linearized reading' (duration: 302.062724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:05:42.722597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:05:42.420157Z","time spent":"302.430402ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T15:05:42.726554Z","caller":"traceutil/trace.go:171","msg":"trace[1311930730] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"281.661559ms","start":"2026-02-25T15:05:42.444869Z","end":"2026-02-25T15:05:42.72653Z","steps":["trace[1311930730] 'process raft request' (duration: 281.486455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:05:42.726581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.882123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:05:42.726651Z","caller":"traceutil/trace.go:171","msg":"trace[1384284336] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1743; }","duration":"103.986026ms","start":"2026-02-25T15:05:42.622649Z","end":"2026-02-25T15:05:42.726635Z","steps":["trace[1384284336] 'agreement among raft nodes before linearized reading' (duration: 103.843892ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:05:48.878238Z","caller":"traceutil/trace.go:171","msg":"trace[2144884691] linearizableReadLoop","detail":"{readStateIndex:1835; appliedIndex:1834; }","duration":"103.546905ms","start":"2026-02-25T15:05:48.774632Z","end":"2026-02-25T15:05:48.878179Z","steps":["trace[2144884691] 'read index received' (duration: 20.187669ms)","trace[2144884691] 'applied index is now lower than readState.Index' (duration: 83.357616ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:05:48.87828Z","caller":"traceutil/trace.go:171","msg":"trace[132643583] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"133.380013ms","start":"2026-02-25T15:05:48.744874Z","end":"2026-02-25T15:05:48.878254Z","steps":["trace[132643583] 'process raft request' (duration: 49.925504ms)","trace[132643583] 'compare' (duration: 83.175093ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:05:48.878476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.821763ms","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-25T15:05:48.878512Z","caller":"traceutil/trace.go:171","msg":"trace[1870372751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1770; }","duration":"103.918885ms","start":"2026-02-25T15:05:48.774585Z","end":"2026-02-25T15:05:48.878504Z","steps":["trace[1870372751] 'agreement among raft nodes before linearized reading' (duration: 103.74499ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:06:07.135313Z","caller":"traceutil/trace.go:171","msg":"trace[644858187] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"132.824579ms","start":"2026-02-25T15:06:07.00246Z","end":"2026-02-25T15:06:07.135285Z","steps":["trace[644858187] 'read index received' (duration: 130.988452ms)","trace[644858187] 'applied index is now lower than readState.Index' (duration: 1.834807ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:06:07.135456Z","caller":"traceutil/trace.go:171","msg":"trace[822494648] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"154.958174ms","start":"2026-02-25T15:06:06.98046Z","end":"2026-02-25T15:06:07.135418Z","steps":["trace[822494648] 'process raft request' (duration: 152.982654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:06:07.135599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.125338ms","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-25T15:06:07.135654Z","caller":"traceutil/trace.go:171","msg":"trace[643640429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1869; }","duration":"133.234091ms","start":"2026-02-25T15:06:07.002405Z","end":"2026-02-25T15:06:07.135639Z","steps":["trace[643640429] 'agreement among raft nodes before linearized reading' (duration: 133.014014ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:06:12.191669Z","caller":"traceutil/trace.go:171","msg":"trace[256652031] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"110.185453ms","start":"2026-02-25T15:06:12.081455Z","end":"2026-02-25T15:06:12.19164Z","steps":["trace[256652031] 'process raft request' (duration: 65.626847ms)","trace[256652031] 'compare' (duration: 44.448052ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:06:12.867852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.206618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T15:06:12.867961Z","caller":"traceutil/trace.go:171","msg":"trace[147263369] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1896; }","duration":"137.370033ms","start":"2026-02-25T15:06:12.730565Z","end":"2026-02-25T15:06:12.867935Z","steps":["trace[147263369] 'count revisions from in-memory index tree' (duration: 137.102965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:06:12.868012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.786576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:06:12.868058Z","caller":"traceutil/trace.go:171","msg":"trace[434251594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"218.266169ms","start":"2026-02-25T15:06:12.649781Z","end":"2026-02-25T15:06:12.868047Z","steps":["trace[434251594] 'range keys from in-memory index tree' (duration: 217.734915ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:06:13.28006Z","caller":"traceutil/trace.go:171","msg":"trace[751274922] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1969; }","duration":"120.566233ms","start":"2026-02-25T15:06:13.15947Z","end":"2026-02-25T15:06:13.280036Z","steps":["trace[751274922] 'read index received' (duration: 120.289176ms)","trace[751274922] 'applied index is now lower than readState.Index' (duration: 276.197µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:06:13.280116Z","caller":"traceutil/trace.go:171","msg":"trace[727166527] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"125.279906ms","start":"2026-02-25T15:06:13.154809Z","end":"2026-02-25T15:06:13.280088Z","steps":["trace[727166527] 'process raft request' (duration: 125.0081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:06:13.280369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.878461ms","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-25T15:06:13.280439Z","caller":"traceutil/trace.go:171","msg":"trace[35482445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1899; }","duration":"121.005774ms","start":"2026-02-25T15:06:13.159415Z","end":"2026-02-25T15:06:13.280421Z","steps":["trace[35482445] 'agreement among raft nodes before linearized reading' (duration: 120.719236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:06:18.942365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.600609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:06:18.94257Z","caller":"traceutil/trace.go:171","msg":"trace[296184697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1924; }","duration":"293.866146ms","start":"2026-02-25T15:06:18.648677Z","end":"2026-02-25T15:06:18.942543Z","steps":["trace[296184697] 'range keys from in-memory index tree' (duration: 293.505317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:06:18.943277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.022289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:06:18.943411Z","caller":"traceutil/trace.go:171","msg":"trace[1023623676] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:1924; }","duration":"393.210554ms","start":"2026-02-25T15:06:18.550172Z","end":"2026-02-25T15:06:18.943382Z","steps":["trace[1023623676] 'count revisions from in-memory index tree' (duration: 392.403193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:06:18.943463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:06:18.550149Z","time spent":"393.302867ms","remote":"127.0.0.1:40266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-02-25T15:06:19.683454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.852444ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T15:06:19.683844Z","caller":"traceutil/trace.go:171","msg":"trace[1067562683] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1997; }","duration":"546.208906ms","start":"2026-02-25T15:06:19.137609Z","end":"2026-02-25T15:06:19.683818Z","steps":["trace[1067562683] 'read index received' (duration: 123.851689ms)","trace[1067562683] 'applied index is now lower than readState.Index' (duration: 422.354907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:06:19.684029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.410451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T15:06:19.684095Z","caller":"traceutil/trace.go:171","msg":"trace[1481974684] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1926; }","duration":"546.493793ms","start":"2026-02-25T15:06:19.137589Z","end":"2026-02-25T15:06:19.684083Z","steps":["trace[1481974684] 'agreement among raft nodes before linearized reading' (duration: 546.367679ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:06:19.684096Z","caller":"traceutil/trace.go:171","msg":"trace[1252187265] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"384.930668ms","start":"2026-02-25T15:06:19.29914Z","end":"2026-02-25T15:06:19.684071Z","steps":["trace[1252187265] 'process raft request' (duration: 384.443445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:06:19.684129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:06:19.137519Z","time spent":"546.600086ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":13,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-25T15:06:19.684252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:06:19.299105Z","time spent":"385.045541ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:06:20.226828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.080741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:06:20.226902Z","caller":"traceutil/trace.go:171","msg":"trace[222276340] linearizableReadLoop","detail":"{readStateIndex:2000; appliedIndex:1999; }","duration":"542.983262ms","start":"2026-02-25T15:06:19.683908Z","end":"2026-02-25T15:06:20.226892Z","steps":["trace[222276340] 'read index received' (duration: 318.721777ms)","trace[222276340] 'applied index is now lower than readState.Index' (duration: 224.260745ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:06:20.22708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.570925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T15:06:20.22718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.137901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5324"} {"level":"info","ts":"2026-02-25T15:06:20.227171Z","caller":"traceutil/trace.go:171","msg":"trace[950163389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1927; }","duration":"546.709ms","start":"2026-02-25T15:06:19.680445Z","end":"2026-02-25T15:06:20.227154Z","steps":["trace[950163389] 'agreement among raft nodes before linearized reading' (duration: 546.453813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:06:20.227219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:06:19.680421Z","time spent":"546.785671ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T15:06:20.227306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.444356ms","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-25T15:06:20.227331Z","caller":"traceutil/trace.go:171","msg":"trace[785225155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1927; }","duration":"917.507168ms","start":"2026-02-25T15:06:19.309818Z","end":"2026-02-25T15:06:20.227326Z","steps":["trace[785225155] 'agreement among raft nodes before linearized reading' (duration: 917.427146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:06:20.227118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.941234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T15:06:20.227232Z","caller":"traceutil/trace.go:171","msg":"trace[1618561470] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1927; }","duration":"825.190603ms","start":"2026-02-25T15:06:19.402003Z","end":"2026-02-25T15:06:20.227194Z","steps":["trace[1618561470] 'agreement among raft nodes before linearized reading' (duration: 825.130011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:06:20.227349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:06:19.309785Z","time spent":"917.559649ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T15:06:20.227554Z","caller":"traceutil/trace.go:171","msg":"trace[1985014182] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:1927; }","duration":"740.387906ms","start":"2026-02-25T15:06:19.487131Z","end":"2026-02-25T15:06:20.227519Z","steps":["trace[1985014182] 'agreement among raft nodes before linearized reading' (duration: 739.954354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:06:20.227591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:06:19.401984Z","time spent":"825.570503ms","remote":"127.0.0.1:34694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5346,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-25T15:06:20.227651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:06:19.487112Z","time spent":"740.502298ms","remote":"127.0.0.1:35410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":29,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-02-25T15:06:20.227713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.2618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T15:06:20.22766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.001081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:06:20.22779Z","caller":"traceutil/trace.go:171","msg":"trace[810924757] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1927; }","duration":"106.438034ms","start":"2026-02-25T15:06:20.121333Z","end":"2026-02-25T15:06:20.227771Z","steps":["trace[810924757] 'agreement among raft nodes before linearized reading' (duration: 106.237479ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:06:20.227829Z","caller":"traceutil/trace.go:171","msg":"trace[2023659660] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1927; }","duration":"247.253537ms","start":"2026-02-25T15:06:19.980551Z","end":"2026-02-25T15:06:20.227805Z","steps":["trace[2023659660] 'agreement among raft nodes before linearized reading' (duration: 246.906958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:06:20.227772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.588709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T15:06:20.227856Z","caller":"traceutil/trace.go:171","msg":"trace[1734176754] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"616.045003ms","start":"2026-02-25T15:06:19.611718Z","end":"2026-02-25T15:06:20.227763Z","steps":["trace[1734176754] 'process raft request' (duration: 390.969756ms)","trace[1734176754] 'compare' (duration: 223.954448ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:06:20.228063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:06:19.61169Z","time spent":"616.260179ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:06:20.227967Z","caller":"traceutil/trace.go:171","msg":"trace[935770862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1927; }","duration":"116.751753ms","start":"2026-02-25T15:06:20.11114Z","end":"2026-02-25T15:06:20.227891Z","steps":["trace[935770862] 'agreement among raft nodes before linearized reading' (duration: 116.575439ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:06:20.347535Z","caller":"traceutil/trace.go:171","msg":"trace[373253240] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"114.426112ms","start":"2026-02-25T15:06:20.233082Z","end":"2026-02-25T15:06:20.347508Z","steps":["trace[373253240] 'process raft request' (duration: 114.373561ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:06:20.347593Z","caller":"traceutil/trace.go:171","msg":"trace[457991989] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"114.523995ms","start":"2026-02-25T15:06:20.233045Z","end":"2026-02-25T15:06:20.347569Z","steps":["trace[457991989] 'process raft request' (duration: 114.000552ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:06:20.348408Z","caller":"traceutil/trace.go:171","msg":"trace[1033007238] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"115.275425ms","start":"2026-02-25T15:06:20.233118Z","end":"2026-02-25T15:06:20.348393Z","steps":["trace[1033007238] 'process raft request' (duration: 115.161742ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:06:20.348442Z","caller":"traceutil/trace.go:171","msg":"trace[1488018200] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"115.096461ms","start":"2026-02-25T15:06:20.233325Z","end":"2026-02-25T15:06:20.348422Z","steps":["trace[1488018200] 'process raft request' (duration: 115.03227ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:07:15.082188Z","caller":"traceutil/trace.go:171","msg":"trace[268975469] transaction","detail":"{read_only:false; response_revision:2367; number_of_response:1; }","duration":"423.625197ms","start":"2026-02-25T15:07:14.658536Z","end":"2026-02-25T15:07:15.082162Z","steps":["trace[268975469] 'process raft request' (duration: 423.464313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:07:15.082354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:07:14.658519Z","time spent":"423.75851ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:07:15.176743Z","caller":"traceutil/trace.go:171","msg":"trace[1134318784] linearizableReadLoop","detail":"{readStateIndex:2458; appliedIndex:2456; }","duration":"517.146735ms","start":"2026-02-25T15:07:14.659571Z","end":"2026-02-25T15:07:15.176718Z","steps":["trace[1134318784] 'read index received' (duration: 422.430176ms)","trace[1134318784] 'applied index is now lower than readState.Index' (duration: 94.712809ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:07:15.176862Z","caller":"traceutil/trace.go:171","msg":"trace[1562322335] transaction","detail":"{read_only:false; response_revision:2368; number_of_response:1; }","duration":"518.220193ms","start":"2026-02-25T15:07:14.658613Z","end":"2026-02-25T15:07:15.176833Z","steps":["trace[1562322335] 'process raft request' (duration: 496.434885ms)","trace[1562322335] 'compare' (duration: 21.557152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:07:15.177001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:07:14.658593Z","time spent":"518.337145ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:07:15.177007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.420072ms","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-25T15:07:15.177077Z","caller":"traceutil/trace.go:171","msg":"trace[1281123908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2368; }","duration":"517.520375ms","start":"2026-02-25T15:07:14.659538Z","end":"2026-02-25T15:07:15.177058Z","steps":["trace[1281123908] 'agreement among raft nodes before linearized reading' (duration: 517.325109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:07:15.177132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:07:14.659522Z","time spent":"517.597987ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T15:07:15.190706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.850302ms","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-25T15:07:15.190769Z","caller":"traceutil/trace.go:171","msg":"trace[1509421758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2369; }","duration":"530.944704ms","start":"2026-02-25T15:07:14.659809Z","end":"2026-02-25T15:07:15.190754Z","steps":["trace[1509421758] 'agreement among raft nodes before linearized reading' (duration: 530.744089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:07:15.190807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:07:14.659798Z","time spent":"530.999376ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T15:07:15.191034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.309338ms","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-25T15:07:15.191089Z","caller":"traceutil/trace.go:171","msg":"trace[2037311966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2369; }","duration":"517.38913ms","start":"2026-02-25T15:07:14.673687Z","end":"2026-02-25T15:07:15.191076Z","steps":["trace[2037311966] 'agreement among raft nodes before linearized reading' (duration: 517.243315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:07:15.191126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:07:14.673679Z","time spent":"517.434341ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T15:07:24.018755Z","caller":"traceutil/trace.go:171","msg":"trace[1815934877] transaction","detail":"{read_only:false; response_revision:2422; number_of_response:1; }","duration":"109.845883ms","start":"2026-02-25T15:07:23.908874Z","end":"2026-02-25T15:07:24.01872Z","steps":["trace[1815934877] 'process raft request' (duration: 109.633538ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:07:48.23161Z","caller":"traceutil/trace.go:171","msg":"trace[1655331675] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"100.163951ms","start":"2026-02-25T15:07:48.131415Z","end":"2026-02-25T15:07:48.231579Z","steps":["trace[1655331675] 'process raft request' (duration: 99.959186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:07:48.614815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.913582ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:07:48.614911Z","caller":"traceutil/trace.go:171","msg":"trace[1942031812] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2553; }","duration":"271.021766ms","start":"2026-02-25T15:07:48.343875Z","end":"2026-02-25T15:07:48.614897Z","steps":["trace[1942031812] 'range keys from in-memory index tree' (duration: 270.898202ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:07:48.715673Z","caller":"traceutil/trace.go:171","msg":"trace[407895126] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"184.046417ms","start":"2026-02-25T15:07:48.531596Z","end":"2026-02-25T15:07:48.715642Z","steps":["trace[407895126] 'process raft request' (duration: 183.796521ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:08:42.021739Z","caller":"traceutil/trace.go:171","msg":"trace[1950624924] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"184.233692ms","start":"2026-02-25T15:08:41.837451Z","end":"2026-02-25T15:08:42.021684Z","steps":["trace[1950624924] 'process raft request' (duration: 183.912573ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:08:42.221186Z","caller":"traceutil/trace.go:171","msg":"trace[1210782071] transaction","detail":"{read_only:false; response_revision:2818; number_of_response:1; }","duration":"383.141808ms","start":"2026-02-25T15:08:41.838012Z","end":"2026-02-25T15:08:42.221154Z","steps":["trace[1210782071] 'process raft request' (duration: 382.925751ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:08:42.221478Z","caller":"traceutil/trace.go:171","msg":"trace[894231846] linearizableReadLoop","detail":"{readStateIndex:2928; appliedIndex:2924; }","duration":"224.995801ms","start":"2026-02-25T15:08:41.99645Z","end":"2026-02-25T15:08:42.221446Z","steps":["trace[894231846] 'read index received' (duration: 24.976108ms)","trace[894231846] 'applied index is now lower than readState.Index' (duration: 200.018633ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:08:42.221657Z","caller":"traceutil/trace.go:171","msg":"trace[1986791872] transaction","detail":"{read_only:false; response_revision:2820; number_of_response:1; }","duration":"381.995566ms","start":"2026-02-25T15:08:41.839649Z","end":"2026-02-25T15:08:42.221644Z","steps":["trace[1986791872] 'process raft request' (duration: 381.666288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:08:42.221778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:08:41.838005Z","time spent":"383.440675ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:08:42.221868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:08:41.839643Z","time spent":"382.049747ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:08:42.221991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.519474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12015"} {"level":"info","ts":"2026-02-25T15:08:42.222072Z","caller":"traceutil/trace.go:171","msg":"trace[169934711] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2820; }","duration":"225.670148ms","start":"2026-02-25T15:08:41.996382Z","end":"2026-02-25T15:08:42.222053Z","steps":["trace[169934711] 'agreement among raft nodes before linearized reading' (duration: 225.350679ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:08:42.221827Z","caller":"traceutil/trace.go:171","msg":"trace[321229812] transaction","detail":"{read_only:false; response_revision:2819; number_of_response:1; }","duration":"382.89817ms","start":"2026-02-25T15:08:41.838764Z","end":"2026-02-25T15:08:42.221662Z","steps":["trace[321229812] 'process raft request' (duration: 382.340626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:08:42.222479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:08:41.838758Z","time spent":"383.410824ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:08:46.412936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.424424ms","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-25T15:08:46.413238Z","caller":"traceutil/trace.go:171","msg":"trace[33536869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2837; }","duration":"175.524507ms","start":"2026-02-25T15:08:46.237471Z","end":"2026-02-25T15:08:46.412995Z","steps":["trace[33536869] 'range keys from in-memory index tree' (duration: 175.299711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:08:46.412939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.286632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-02-25T15:08:46.413289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.965794ms","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-25T15:08:46.413406Z","caller":"traceutil/trace.go:171","msg":"trace[1900014998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2837; }","duration":"177.150879ms","start":"2026-02-25T15:08:46.236227Z","end":"2026-02-25T15:08:46.413377Z","steps":["trace[1900014998] 'range keys from in-memory index tree' (duration: 176.543143ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:08:46.413408Z","caller":"traceutil/trace.go:171","msg":"trace[544168702] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2837; }","duration":"135.780715ms","start":"2026-02-25T15:08:46.277587Z","end":"2026-02-25T15:08:46.413368Z","steps":["trace[544168702] 'range keys from in-memory index tree' (duration: 135.101988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:08:46.414164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.4384ms","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-25T15:08:46.414222Z","caller":"traceutil/trace.go:171","msg":"trace[323864087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2837; }","duration":"176.559933ms","start":"2026-02-25T15:08:46.237651Z","end":"2026-02-25T15:08:46.414211Z","steps":["trace[323864087] 'range keys from in-memory index tree' (duration: 175.513057ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:09:47.034077Z","caller":"traceutil/trace.go:171","msg":"trace[1427105415] transaction","detail":"{read_only:false; response_revision:3170; number_of_response:1; }","duration":"313.205718ms","start":"2026-02-25T15:09:46.720847Z","end":"2026-02-25T15:09:47.034053Z","steps":["trace[1427105415] 'process raft request' (duration: 313.152447ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:09:47.034124Z","caller":"traceutil/trace.go:171","msg":"trace[894052944] transaction","detail":"{read_only:false; response_revision:3169; number_of_response:1; }","duration":"313.309202ms","start":"2026-02-25T15:09:46.720769Z","end":"2026-02-25T15:09:47.034078Z","steps":["trace[894052944] 'process raft request' (duration: 235.858001ms)","trace[894052944] 'compare' (duration: 77.177804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:09:47.034233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:09:46.720821Z","time spent":"313.335031ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:09:47.034333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:09:46.720754Z","time spent":"313.459615ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:09:47.05114Z","caller":"traceutil/trace.go:171","msg":"trace[544449111] transaction","detail":"{read_only:false; response_revision:3171; number_of_response:1; }","duration":"195.465057ms","start":"2026-02-25T15:09:46.855648Z","end":"2026-02-25T15:09:47.051113Z","steps":["trace[544449111] 'process raft request' (duration: 195.310634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:09:47.292034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.619404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T15:09:47.292226Z","caller":"traceutil/trace.go:171","msg":"trace[648790610] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:3171; }","duration":"125.890921ms","start":"2026-02-25T15:09:47.166302Z","end":"2026-02-25T15:09:47.292193Z","steps":["trace[648790610] 'count revisions from in-memory index tree' (duration: 125.471879ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:10:24.477663Z","caller":"traceutil/trace.go:171","msg":"trace[1071369743] linearizableReadLoop","detail":"{readStateIndex:3629; appliedIndex:3628; }","duration":"195.17786ms","start":"2026-02-25T15:10:24.282462Z","end":"2026-02-25T15:10:24.47764Z","steps":["trace[1071369743] 'read index received' (duration: 194.965204ms)","trace[1071369743] 'applied index is now lower than readState.Index' (duration: 211.726µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:10:24.477788Z","caller":"traceutil/trace.go:171","msg":"trace[193035161] transaction","detail":"{read_only:false; response_revision:3494; number_of_response:1; }","duration":"220.886275ms","start":"2026-02-25T15:10:24.25689Z","end":"2026-02-25T15:10:24.477777Z","steps":["trace[193035161] 'process raft request' (duration: 220.610137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:10:24.478066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.590221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12015"} {"level":"info","ts":"2026-02-25T15:10:24.47811Z","caller":"traceutil/trace.go:171","msg":"trace[992624592] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3494; }","duration":"195.687054ms","start":"2026-02-25T15:10:24.282413Z","end":"2026-02-25T15:10:24.4781Z","steps":["trace[992624592] 'agreement among raft nodes before linearized reading' (duration: 195.52768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:10:24.478209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.152657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T15:10:24.478339Z","caller":"traceutil/trace.go:171","msg":"trace[586766219] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:3494; }","duration":"131.340183ms","start":"2026-02-25T15:10:24.346978Z","end":"2026-02-25T15:10:24.478319Z","steps":["trace[586766219] 'agreement among raft nodes before linearized reading' (duration: 131.064755ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:42.661443Z","caller":"traceutil/trace.go:171","msg":"trace[1156384018] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4407; }","duration":"171.984601ms","start":"2026-02-25T15:11:42.489425Z","end":"2026-02-25T15:11:42.66141Z","steps":["trace[1156384018] 'process raft request' (duration: 168.859189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:43.986628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.003761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:11:43.98698Z","caller":"traceutil/trace.go:171","msg":"trace[1791972451] linearizableReadLoop","detail":"{readStateIndex:4592; appliedIndex:4590; }","duration":"426.892021ms","start":"2026-02-25T15:11:43.560039Z","end":"2026-02-25T15:11:43.986931Z","steps":["trace[1791972451] 'read index received' (duration: 45.4979ms)","trace[1791972451] 'applied index is now lower than readState.Index' (duration: 381.383891ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:11:43.986995Z","caller":"traceutil/trace.go:171","msg":"trace[1519671092] transaction","detail":"{read_only:false; response_revision:4419; number_of_response:1; }","duration":"573.743418ms","start":"2026-02-25T15:11:43.413203Z","end":"2026-02-25T15:11:43.986946Z","steps":["trace[1519671092] 'process raft request' (duration: 192.320576ms)","trace[1519671092] 'compare' (duration: 380.755385ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:11:43.987006Z","caller":"traceutil/trace.go:171","msg":"trace[609122955] transaction","detail":"{read_only:false; response_revision:4420; number_of_response:1; }","duration":"555.062225ms","start":"2026-02-25T15:11:43.431879Z","end":"2026-02-25T15:11:43.986941Z","steps":["trace[609122955] 'process raft request' (duration: 554.86058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:43.987193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:11:43.413177Z","time spent":"573.915134ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:11:43.987212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.167497ms","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":"warn","ts":"2026-02-25T15:11:43.987238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:11:43.431852Z","time spent":"555.263609ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:11:43.987271Z","caller":"traceutil/trace.go:171","msg":"trace[1754199843] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:4421; }","duration":"427.299651ms","start":"2026-02-25T15:11:43.559958Z","end":"2026-02-25T15:11:43.987257Z","steps":["trace[1754199843] 'agreement among raft nodes before linearized reading' (duration: 427.134427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:43.987316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:11:43.559929Z","time spent":"427.377044ms","remote":"127.0.0.1:39550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":24,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-25T15:11:43.987317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.552799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a6b4a974-816a-4bc4-bc55-e1e44f5361bb\" ","response":"range_response_count:1 size:4001"} {"level":"warn","ts":"2026-02-25T15:11:43.987386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.971045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:11:43.987402Z","caller":"traceutil/trace.go:171","msg":"trace[161024301] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a6b4a974-816a-4bc4-bc55-e1e44f5361bb; range_end:; response_count:1; response_revision:4421; }","duration":"294.695153ms","start":"2026-02-25T15:11:43.692687Z","end":"2026-02-25T15:11:43.987382Z","steps":["trace[161024301] 'agreement among raft nodes before linearized reading' (duration: 294.454816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:43.987364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.256006ms","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-25T15:11:43.987497Z","caller":"traceutil/trace.go:171","msg":"trace[1831080504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4421; }","duration":"406.448121ms","start":"2026-02-25T15:11:43.581021Z","end":"2026-02-25T15:11:43.987469Z","steps":["trace[1831080504] 'agreement among raft nodes before linearized reading' (duration: 406.126992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:43.98755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:11:43.580996Z","time spent":"406.544194ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T15:11:43.987581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.794905ms","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-25T15:11:43.987611Z","caller":"traceutil/trace.go:171","msg":"trace[26158157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4421; }","duration":"400.853725ms","start":"2026-02-25T15:11:43.586748Z","end":"2026-02-25T15:11:43.987602Z","steps":["trace[26158157] 'agreement among raft nodes before linearized reading' (duration: 400.707752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:43.987645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:11:43.586729Z","time spent":"400.908677ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T15:11:43.987431Z","caller":"traceutil/trace.go:171","msg":"trace[1545274290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4421; }","duration":"338.056327ms","start":"2026-02-25T15:11:43.64936Z","end":"2026-02-25T15:11:43.987416Z","steps":["trace[1545274290] 'agreement among raft nodes before linearized reading' (duration: 337.980885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:43.987663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.024171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-25T15:11:43.987681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:11:43.649335Z","time spent":"338.339044ms","remote":"127.0.0.1:39230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T15:11:43.98769Z","caller":"traceutil/trace.go:171","msg":"trace[274375889] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:4421; }","duration":"160.089172ms","start":"2026-02-25T15:11:43.827593Z","end":"2026-02-25T15:11:43.987683Z","steps":["trace[274375889] 'agreement among raft nodes before linearized reading' (duration: 160.00489ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:43.987723Z","caller":"traceutil/trace.go:171","msg":"trace[2094757561] transaction","detail":"{read_only:false; response_revision:4421; number_of_response:1; }","duration":"204.601376ms","start":"2026-02-25T15:11:43.782303Z","end":"2026-02-25T15:11:43.986904Z","steps":["trace[2094757561] 'process raft request' (duration: 204.553785ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:46.831371Z","caller":"traceutil/trace.go:171","msg":"trace[658873685] transaction","detail":"{read_only:false; response_revision:4484; number_of_response:1; }","duration":"129.252183ms","start":"2026-02-25T15:11:46.7021Z","end":"2026-02-25T15:11:46.831352Z","steps":["trace[658873685] 'process raft request' (duration: 91.659897ms)","trace[658873685] 'compare' (duration: 37.194355ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:11:47.076521Z","caller":"traceutil/trace.go:171","msg":"trace[1344155608] linearizableReadLoop","detail":"{readStateIndex:4665; appliedIndex:4661; }","duration":"201.578708ms","start":"2026-02-25T15:11:46.874928Z","end":"2026-02-25T15:11:47.076507Z","steps":["trace[1344155608] 'read index received' (duration: 29.434274ms)","trace[1344155608] 'applied index is now lower than readState.Index' (duration: 172.143894ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:11:47.076551Z","caller":"traceutil/trace.go:171","msg":"trace[1449356785] transaction","detail":"{read_only:false; response_revision:4489; number_of_response:1; }","duration":"204.093742ms","start":"2026-02-25T15:11:46.872429Z","end":"2026-02-25T15:11:47.076523Z","steps":["trace[1449356785] 'process raft request' (duration: 203.970909ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:47.076637Z","caller":"traceutil/trace.go:171","msg":"trace[546920708] transaction","detail":"{read_only:false; response_revision:4488; number_of_response:1; }","duration":"204.345439ms","start":"2026-02-25T15:11:46.87222Z","end":"2026-02-25T15:11:47.076565Z","steps":["trace[546920708] 'process raft request' (duration: 204.086223ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:47.076701Z","caller":"traceutil/trace.go:171","msg":"trace[1976356835] transaction","detail":"{read_only:false; response_revision:4490; number_of_response:1; }","duration":"204.126843ms","start":"2026-02-25T15:11:46.87256Z","end":"2026-02-25T15:11:47.076687Z","steps":["trace[1976356835] 'process raft request' (duration: 203.859277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:47.076771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.824964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:11:47.076755Z","caller":"traceutil/trace.go:171","msg":"trace[1809482412] transaction","detail":"{read_only:false; response_revision:4492; number_of_response:1; }","duration":"201.462694ms","start":"2026-02-25T15:11:46.875239Z","end":"2026-02-25T15:11:47.076702Z","steps":["trace[1809482412] 'process raft request' (duration: 201.213608ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:47.076831Z","caller":"traceutil/trace.go:171","msg":"trace[261160678] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4493; }","duration":"201.928688ms","start":"2026-02-25T15:11:46.874892Z","end":"2026-02-25T15:11:47.07682Z","steps":["trace[261160678] 'agreement among raft nodes before linearized reading' (duration: 201.786123ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:47.076866Z","caller":"traceutil/trace.go:171","msg":"trace[1010557688] transaction","detail":"{read_only:false; response_revision:4493; number_of_response:1; }","duration":"192.971664ms","start":"2026-02-25T15:11:46.883882Z","end":"2026-02-25T15:11:47.076854Z","steps":["trace[1010557688] 'process raft request' (duration: 192.586584ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:47.076767Z","caller":"traceutil/trace.go:171","msg":"trace[2093080649] transaction","detail":"{read_only:false; response_revision:4491; number_of_response:1; }","duration":"201.568367ms","start":"2026-02-25T15:11:46.875173Z","end":"2026-02-25T15:11:47.076742Z","steps":["trace[2093080649] 'process raft request' (duration: 201.264109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:47.077388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.133078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2194600"} {"level":"info","ts":"2026-02-25T15:11:47.07743Z","caller":"traceutil/trace.go:171","msg":"trace[1165046595] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4493; }","duration":"144.21407ms","start":"2026-02-25T15:11:46.933205Z","end":"2026-02-25T15:11:47.077419Z","steps":["trace[1165046595] 'agreement among raft nodes before linearized reading' (duration: 143.645456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:47.078795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.018598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T15:11:47.078872Z","caller":"traceutil/trace.go:171","msg":"trace[1013034934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4493; }","duration":"163.135581ms","start":"2026-02-25T15:11:46.915727Z","end":"2026-02-25T15:11:47.078862Z","steps":["trace[1013034934] 'agreement among raft nodes before linearized reading' (duration: 161.063327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:48.364849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.28419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:11:48.365041Z","caller":"traceutil/trace.go:171","msg":"trace[66226220] linearizableReadLoop","detail":"{readStateIndex:4773; appliedIndex:4771; }","duration":"251.545144ms","start":"2026-02-25T15:11:48.11348Z","end":"2026-02-25T15:11:48.365025Z","steps":["trace[66226220] 'read index received' (duration: 32.981085ms)","trace[66226220] 'applied index is now lower than readState.Index' (duration: 218.562749ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:11:48.365125Z","caller":"traceutil/trace.go:171","msg":"trace[1288034547] transaction","detail":"{read_only:false; response_revision:4593; number_of_response:1; }","duration":"308.276235ms","start":"2026-02-25T15:11:48.056793Z","end":"2026-02-25T15:11:48.36507Z","steps":["trace[1288034547] 'process raft request' (duration: 89.646915ms)","trace[1288034547] 'compare' (duration: 218.149717ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:11:48.365238Z","caller":"traceutil/trace.go:171","msg":"trace[478911478] transaction","detail":"{read_only:false; response_revision:4594; number_of_response:1; }","duration":"307.289059ms","start":"2026-02-25T15:11:48.057909Z","end":"2026-02-25T15:11:48.365198Z","steps":["trace[478911478] 'process raft request' (duration: 307.058703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:48.365401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:11:48.056751Z","time spent":"308.48431ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:11:48.36542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:11:48.057868Z","time spent":"307.451723ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:11:48.36557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.085227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:718648"} {"level":"warn","ts":"2026-02-25T15:11:48.365557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.375037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-02-25T15:11:48.365627Z","caller":"traceutil/trace.go:171","msg":"trace[1511359316] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4594; }","duration":"252.178229ms","start":"2026-02-25T15:11:48.113432Z","end":"2026-02-25T15:11:48.36561Z","steps":["trace[1511359316] 'agreement among raft nodes before linearized reading' (duration: 251.691717ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:48.36564Z","caller":"traceutil/trace.go:171","msg":"trace[1954354008] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:4594; }","duration":"244.515821ms","start":"2026-02-25T15:11:48.121106Z","end":"2026-02-25T15:11:48.365622Z","steps":["trace[1954354008] 'agreement among raft nodes before linearized reading' (duration: 244.248344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:48.365801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.042209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-02-25T15:11:48.365938Z","caller":"traceutil/trace.go:171","msg":"trace[1785467702] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:4594; }","duration":"244.240924ms","start":"2026-02-25T15:11:48.121668Z","end":"2026-02-25T15:11:48.365909Z","steps":["trace[1785467702] 'agreement among raft nodes before linearized reading' (duration: 243.892924ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:50.127718Z","caller":"traceutil/trace.go:171","msg":"trace[1675739480] linearizableReadLoop","detail":"{readStateIndex:4795; appliedIndex:4794; }","duration":"103.771611ms","start":"2026-02-25T15:11:50.023913Z","end":"2026-02-25T15:11:50.127685Z","steps":["trace[1675739480] 'read index received' (duration: 103.658398ms)","trace[1675739480] 'applied index is now lower than readState.Index' (duration: 111.673µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:11:50.12779Z","caller":"traceutil/trace.go:171","msg":"trace[1244254311] transaction","detail":"{read_only:false; response_revision:4615; number_of_response:1; }","duration":"103.941265ms","start":"2026-02-25T15:11:50.023797Z","end":"2026-02-25T15:11:50.127738Z","steps":["trace[1244254311] 'process raft request' (duration: 103.667399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:50.127953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.029498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-25T15:11:50.128022Z","caller":"traceutil/trace.go:171","msg":"trace[794269372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4615; }","duration":"104.146081ms","start":"2026-02-25T15:11:50.023863Z","end":"2026-02-25T15:11:50.128009Z","steps":["trace[794269372] 'agreement among raft nodes before linearized reading' (duration: 103.924495ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:50.129055Z","caller":"traceutil/trace.go:171","msg":"trace[1599503404] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"105.122236ms","start":"2026-02-25T15:11:50.023923Z","end":"2026-02-25T15:11:50.129045Z","steps":["trace[1599503404] 'process raft request' (duration: 104.788387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:51.58987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.821961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T15:11:51.589909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.572694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12015"} {"level":"info","ts":"2026-02-25T15:11:51.58998Z","caller":"traceutil/trace.go:171","msg":"trace[1259326453] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4644; }","duration":"428.717827ms","start":"2026-02-25T15:11:51.161245Z","end":"2026-02-25T15:11:51.589962Z","steps":["trace[1259326453] 'range keys from in-memory index tree' (duration: 428.305437ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:51.589979Z","caller":"traceutil/trace.go:171","msg":"trace[887129476] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4644; }","duration":"156.004596ms","start":"2026-02-25T15:11:51.43395Z","end":"2026-02-25T15:11:51.589955Z","steps":["trace[887129476] 'count revisions from in-memory index tree' (duration: 155.667807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:51.589964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.395758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-m6zgx-tls\" ","response":"range_response_count:1 size:1967"} {"level":"warn","ts":"2026-02-25T15:11:51.590013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.355947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T15:11:51.590035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.67833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T15:11:51.590088Z","caller":"traceutil/trace.go:171","msg":"trace[417822852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4644; }","duration":"362.443559ms","start":"2026-02-25T15:11:51.22763Z","end":"2026-02-25T15:11:51.590073Z","steps":["trace[417822852] 'range keys from in-memory index tree' (duration: 362.048199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:51.590012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:11:51.161224Z","time spent":"428.780549ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12037,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-02-25T15:11:51.590131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:11:51.22757Z","time spent":"362.553872ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T15:11:51.590141Z","caller":"traceutil/trace.go:171","msg":"trace[25110612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4644; }","duration":"361.860944ms","start":"2026-02-25T15:11:51.22826Z","end":"2026-02-25T15:11:51.590121Z","steps":["trace[25110612] 'range keys from in-memory index tree' (duration: 361.33952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:51.590205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:11:51.228231Z","time spent":"361.960667ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T15:11:51.590105Z","caller":"traceutil/trace.go:171","msg":"trace[1115099511] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-m6zgx-tls; range_end:; response_count:1; response_revision:4644; }","duration":"153.689695ms","start":"2026-02-25T15:11:51.436392Z","end":"2026-02-25T15:11:51.590082Z","steps":["trace[1115099511] 'range keys from in-memory index tree' (duration: 153.118391ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:53.766436Z","caller":"traceutil/trace.go:171","msg":"trace[1048918331] linearizableReadLoop","detail":"{readStateIndex:4867; appliedIndex:4866; }","duration":"116.109891ms","start":"2026-02-25T15:11:53.650284Z","end":"2026-02-25T15:11:53.766394Z","steps":["trace[1048918331] 'read index received' (duration: 75.852717ms)","trace[1048918331] 'applied index is now lower than readState.Index' (duration: 40.255624ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:11:53.766474Z","caller":"traceutil/trace.go:171","msg":"trace[463993862] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"133.787019ms","start":"2026-02-25T15:11:53.632656Z","end":"2026-02-25T15:11:53.766443Z","steps":["trace[463993862] 'process raft request' (duration: 93.531915ms)","trace[463993862] 'compare' (duration: 40.039019ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:11:53.766632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.337326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T15:11:53.766713Z","caller":"traceutil/trace.go:171","msg":"trace[1892016559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4685; }","duration":"116.46579ms","start":"2026-02-25T15:11:53.650231Z","end":"2026-02-25T15:11:53.766697Z","steps":["trace[1892016559] 'agreement among raft nodes before linearized reading' (duration: 116.276015ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:11:54.044907Z","caller":"traceutil/trace.go:171","msg":"trace[836824202] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"152.712331ms","start":"2026-02-25T15:11:53.89217Z","end":"2026-02-25T15:11:54.044883Z","steps":["trace[836824202] 'process raft request' (duration: 136.849839ms)","trace[836824202] 'compare' (duration: 15.752218ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:11:54.057095Z","caller":"traceutil/trace.go:171","msg":"trace[816674987] linearizableReadLoop","detail":"{readStateIndex:4874; appliedIndex:4872; }","duration":"130.061774ms","start":"2026-02-25T15:11:53.927013Z","end":"2026-02-25T15:11:54.057074Z","steps":["trace[816674987] 'read index received' (duration: 102.022756ms)","trace[816674987] 'applied index is now lower than readState.Index' (duration: 28.037918ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:11:54.057624Z","caller":"traceutil/trace.go:171","msg":"trace[1014971769] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"155.41004ms","start":"2026-02-25T15:11:53.902175Z","end":"2026-02-25T15:11:54.057585Z","steps":["trace[1014971769] 'process raft request' (duration: 154.771843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:11:54.057722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.657119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.189785ff411025d6\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-02-25T15:11:54.057851Z","caller":"traceutil/trace.go:171","msg":"trace[1514172393] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.189785ff411025d6; range_end:; response_count:1; response_revision:4691; }","duration":"130.844044ms","start":"2026-02-25T15:11:53.926975Z","end":"2026-02-25T15:11:54.057819Z","steps":["trace[1514172393] 'agreement among raft nodes before linearized reading' (duration: 130.459214ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:01.808319Z","caller":"traceutil/trace.go:171","msg":"trace[876792884] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"424.741755ms","start":"2026-02-25T15:12:01.383562Z","end":"2026-02-25T15:12:01.808304Z","steps":["trace[876792884] 'process raft request' (duration: 424.464277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:01.808412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:01.383534Z","time spent":"424.836548ms","remote":"127.0.0.1:39294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":129714,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:12:01.808396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.464341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:12:01.808307Z","caller":"traceutil/trace.go:171","msg":"trace[539040412] linearizableReadLoop","detail":"{readStateIndex:5036; appliedIndex:5035; }","duration":"293.365338ms","start":"2026-02-25T15:12:01.514923Z","end":"2026-02-25T15:12:01.808288Z","steps":["trace[539040412] 'read index received' (duration: 293.017918ms)","trace[539040412] 'applied index is now lower than readState.Index' (duration: 346.39µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:01.8085Z","caller":"traceutil/trace.go:171","msg":"trace[293344222] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4849; }","duration":"293.646944ms","start":"2026-02-25T15:12:01.514841Z","end":"2026-02-25T15:12:01.808488Z","steps":["trace[293344222] 'agreement among raft nodes before linearized reading' (duration: 293.490271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:01.808651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.197725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:12:01.80871Z","caller":"traceutil/trace.go:171","msg":"trace[989559892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4849; }","duration":"150.303177ms","start":"2026-02-25T15:12:01.658386Z","end":"2026-02-25T15:12:01.808689Z","steps":["trace[989559892] 'agreement among raft nodes before linearized reading' (duration: 150.192235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:01.80872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.923928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-f6f4f7d96-pl6bv\" ","response":"range_response_count:1 size:7679"} {"level":"info","ts":"2026-02-25T15:12:01.808751Z","caller":"traceutil/trace.go:171","msg":"trace[1484369947] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-f6f4f7d96-pl6bv; range_end:; response_count:1; response_revision:4849; }","duration":"149.970379ms","start":"2026-02-25T15:12:01.658772Z","end":"2026-02-25T15:12:01.808742Z","steps":["trace[1484369947] 'agreement among raft nodes before linearized reading' (duration: 149.852546ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:04.350056Z","caller":"traceutil/trace.go:171","msg":"trace[477544717] linearizableReadLoop","detail":"{readStateIndex:5099; appliedIndex:5098; }","duration":"148.700856ms","start":"2026-02-25T15:12:04.201318Z","end":"2026-02-25T15:12:04.350019Z","steps":["trace[477544717] 'read index received' (duration: 146.11805ms)","trace[477544717] 'applied index is now lower than readState.Index' (duration: 2.581236ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:04.350169Z","caller":"traceutil/trace.go:171","msg":"trace[74576800] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"164.035493ms","start":"2026-02-25T15:12:04.186086Z","end":"2026-02-25T15:12:04.350122Z","steps":["trace[74576800] 'process raft request' (duration: 161.379915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:04.350345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.993065ms","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-25T15:12:04.350397Z","caller":"traceutil/trace.go:171","msg":"trace[256091353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4909; }","duration":"149.135678ms","start":"2026-02-25T15:12:04.201245Z","end":"2026-02-25T15:12:04.35038Z","steps":["trace[256091353] 'agreement among raft nodes before linearized reading' (duration: 148.909831ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:04.712669Z","caller":"traceutil/trace.go:171","msg":"trace[491658527] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"185.51721ms","start":"2026-02-25T15:12:04.527111Z","end":"2026-02-25T15:12:04.712628Z","steps":["trace[491658527] 'process raft request' (duration: 185.323836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:10.000027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.043475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T15:12:10.000087Z","caller":"traceutil/trace.go:171","msg":"trace[1209017004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4949; }","duration":"127.175559ms","start":"2026-02-25T15:12:09.872899Z","end":"2026-02-25T15:12:10.000075Z","steps":["trace[1209017004] 'range keys from in-memory index tree' (duration: 126.86198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:10.000081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.49498ms","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-25T15:12:10.000162Z","caller":"traceutil/trace.go:171","msg":"trace[45192211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4949; }","duration":"146.671154ms","start":"2026-02-25T15:12:09.853471Z","end":"2026-02-25T15:12:10.000142Z","steps":["trace[45192211] 'range keys from in-memory index tree' (duration: 146.319744ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:10.000662Z","caller":"traceutil/trace.go:171","msg":"trace[1347970744] linearizableReadLoop","detail":"{readStateIndex:5141; appliedIndex:5140; }","duration":"106.827009ms","start":"2026-02-25T15:12:09.893813Z","end":"2026-02-25T15:12:10.00064Z","steps":["trace[1347970744] 'read index received' (duration: 106.663385ms)","trace[1347970744] 'applied index is now lower than readState.Index' (duration: 162.214µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:10.000776Z","caller":"traceutil/trace.go:171","msg":"trace[449505793] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"127.200899ms","start":"2026-02-25T15:12:09.873565Z","end":"2026-02-25T15:12:10.000766Z","steps":["trace[449505793] 'process raft request' (duration: 126.931971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:10.001012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.178069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-02-25T15:12:10.001189Z","caller":"traceutil/trace.go:171","msg":"trace[624462303] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4950; }","duration":"107.385445ms","start":"2026-02-25T15:12:09.893751Z","end":"2026-02-25T15:12:10.001136Z","steps":["trace[624462303] 'agreement among raft nodes before linearized reading' (duration: 107.021906ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:10.482831Z","caller":"traceutil/trace.go:171","msg":"trace[876252353] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5172; }","duration":"122.048784ms","start":"2026-02-25T15:12:10.360753Z","end":"2026-02-25T15:12:10.482802Z","steps":["trace[876252353] 'read index received' (duration: 115.773342ms)","trace[876252353] 'applied index is now lower than readState.Index' (duration: 6.274572ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:12:10.483183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.365013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-02-25T15:12:10.483263Z","caller":"traceutil/trace.go:171","msg":"trace[72837518] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4982; }","duration":"122.512017ms","start":"2026-02-25T15:12:10.360734Z","end":"2026-02-25T15:12:10.483246Z","steps":["trace[72837518] 'agreement among raft nodes before linearized reading' (duration: 122.200269ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:10.483466Z","caller":"traceutil/trace.go:171","msg":"trace[290215586] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"127.805463ms","start":"2026-02-25T15:12:10.355073Z","end":"2026-02-25T15:12:10.482879Z","steps":["trace[290215586] 'process raft request' (duration: 121.394157ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:10.491778Z","caller":"traceutil/trace.go:171","msg":"trace[219674816] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"122.446375ms","start":"2026-02-25T15:12:10.369314Z","end":"2026-02-25T15:12:10.491761Z","steps":["trace[219674816] 'process raft request' (duration: 122.095015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:10.491932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.847741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-25T15:12:10.492028Z","caller":"traceutil/trace.go:171","msg":"trace[1768446524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4983; }","duration":"112.020725ms","start":"2026-02-25T15:12:10.379989Z","end":"2026-02-25T15:12:10.492009Z","steps":["trace[1768446524] 'agreement among raft nodes before linearized reading' (duration: 111.818ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:10.629328Z","caller":"traceutil/trace.go:171","msg":"trace[748699777] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"130.934115ms","start":"2026-02-25T15:12:10.498356Z","end":"2026-02-25T15:12:10.62929Z","steps":["trace[748699777] 'process raft request' (duration: 130.811642ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:10.629357Z","caller":"traceutil/trace.go:171","msg":"trace[816468137] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"128.081722ms","start":"2026-02-25T15:12:10.501243Z","end":"2026-02-25T15:12:10.629325Z","steps":["trace[816468137] 'process raft request' (duration: 127.975699ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:10.629393Z","caller":"traceutil/trace.go:171","msg":"trace[234908324] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"132.769473ms","start":"2026-02-25T15:12:10.496587Z","end":"2026-02-25T15:12:10.629356Z","steps":["trace[234908324] 'process raft request' (duration: 116.711597ms)","trace[234908324] 'compare' (duration: 15.711187ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:10.767298Z","caller":"traceutil/trace.go:171","msg":"trace[1370626632] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"130.506884ms","start":"2026-02-25T15:12:10.636766Z","end":"2026-02-25T15:12:10.767273Z","steps":["trace[1370626632] 'process raft request' (duration: 130.31338ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:10.767331Z","caller":"traceutil/trace.go:171","msg":"trace[318048451] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"131.417588ms","start":"2026-02-25T15:12:10.635883Z","end":"2026-02-25T15:12:10.767301Z","steps":["trace[318048451] 'process raft request' (duration: 112.669122ms)","trace[318048451] 'compare' (duration: 18.364716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:12:10.767557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.592511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-02-25T15:12:10.767639Z","caller":"traceutil/trace.go:171","msg":"trace[1914477073] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4995; }","duration":"114.719015ms","start":"2026-02-25T15:12:10.652897Z","end":"2026-02-25T15:12:10.767616Z","steps":["trace[1914477073] 'agreement among raft nodes before linearized reading' (duration: 114.476239ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:10.767825Z","caller":"traceutil/trace.go:171","msg":"trace[2076568873] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"130.504584ms","start":"2026-02-25T15:12:10.637309Z","end":"2026-02-25T15:12:10.767814Z","steps":["trace[2076568873] 'process raft request' (duration: 129.820356ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:10.973159Z","caller":"traceutil/trace.go:171","msg":"trace[1554794341] linearizableReadLoop","detail":"{readStateIndex:5193; appliedIndex:5192; }","duration":"104.09414ms","start":"2026-02-25T15:12:10.869035Z","end":"2026-02-25T15:12:10.973129Z","steps":["trace[1554794341] 'read index received' (duration: 54.576485ms)","trace[1554794341] 'applied index is now lower than readState.Index' (duration: 49.516165ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:10.973355Z","caller":"traceutil/trace.go:171","msg":"trace[320585601] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"105.313642ms","start":"2026-02-25T15:12:10.867988Z","end":"2026-02-25T15:12:10.973302Z","steps":["trace[320585601] 'process raft request' (duration: 55.466709ms)","trace[320585601] 'compare' (duration: 49.546465ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:12:10.973517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.448518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-02-25T15:12:10.973581Z","caller":"traceutil/trace.go:171","msg":"trace[1158464925] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5002; }","duration":"104.54149ms","start":"2026-02-25T15:12:10.869019Z","end":"2026-02-25T15:12:10.97356Z","steps":["trace[1158464925] 'agreement among raft nodes before linearized reading' (duration: 104.282464ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:11.00916Z","caller":"traceutil/trace.go:171","msg":"trace[373893136] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"139.18762ms","start":"2026-02-25T15:12:10.86995Z","end":"2026-02-25T15:12:11.009137Z","steps":["trace[373893136] 'process raft request' (duration: 138.907143ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:11.009194Z","caller":"traceutil/trace.go:171","msg":"trace[54638311] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"138.383459ms","start":"2026-02-25T15:12:10.870784Z","end":"2026-02-25T15:12:11.009167Z","steps":["trace[54638311] 'process raft request' (duration: 138.226515ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:11.009436Z","caller":"traceutil/trace.go:171","msg":"trace[815773545] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"135.611967ms","start":"2026-02-25T15:12:10.873795Z","end":"2026-02-25T15:12:11.009407Z","steps":["trace[815773545] 'process raft request' (duration: 135.261778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:11.009465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.918266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-02-25T15:12:11.009565Z","caller":"traceutil/trace.go:171","msg":"trace[2129092664] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5005; }","duration":"138.071751ms","start":"2026-02-25T15:12:10.87147Z","end":"2026-02-25T15:12:11.009542Z","steps":["trace[2129092664] 'agreement among raft nodes before linearized reading' (duration: 137.720232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:11.009589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.458213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:12:11.009691Z","caller":"traceutil/trace.go:171","msg":"trace[1395525187] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:5005; }","duration":"140.610897ms","start":"2026-02-25T15:12:10.86905Z","end":"2026-02-25T15:12:11.009661Z","steps":["trace[1395525187] 'agreement among raft nodes before linearized reading' (duration: 140.281288ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:11.129368Z","caller":"traceutil/trace.go:171","msg":"trace[1095927201] linearizableReadLoop","detail":"{readStateIndex:5198; appliedIndex:5196; }","duration":"108.296259ms","start":"2026-02-25T15:12:11.021051Z","end":"2026-02-25T15:12:11.129347Z","steps":["trace[1095927201] 'read index received' (duration: 41.845445ms)","trace[1095927201] 'applied index is now lower than readState.Index' (duration: 66.449594ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:11.129457Z","caller":"traceutil/trace.go:171","msg":"trace[2019184639] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"109.979522ms","start":"2026-02-25T15:12:11.019417Z","end":"2026-02-25T15:12:11.129396Z","steps":["trace[2019184639] 'process raft request' (duration: 62.342797ms)","trace[2019184639] 'compare' (duration: 47.46728ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:12:11.129552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.400005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-02-25T15:12:11.12958Z","caller":"traceutil/trace.go:171","msg":"trace[135162353] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5007; }","duration":"107.462077ms","start":"2026-02-25T15:12:11.022109Z","end":"2026-02-25T15:12:11.129571Z","steps":["trace[135162353] 'agreement among raft nodes before linearized reading' (duration: 107.360705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:11.129647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.577206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9595"} {"level":"info","ts":"2026-02-25T15:12:11.129737Z","caller":"traceutil/trace.go:171","msg":"trace[639968726] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5007; }","duration":"108.703759ms","start":"2026-02-25T15:12:11.021009Z","end":"2026-02-25T15:12:11.129713Z","steps":["trace[639968726] 'agreement among raft nodes before linearized reading' (duration: 108.443823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:11.129974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.965783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:109535"} {"level":"info","ts":"2026-02-25T15:12:11.13006Z","caller":"traceutil/trace.go:171","msg":"trace[1409605501] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5007; }","duration":"105.110226ms","start":"2026-02-25T15:12:11.024931Z","end":"2026-02-25T15:12:11.130042Z","steps":["trace[1409605501] 'agreement among raft nodes before linearized reading' (duration: 104.737206ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:12.244944Z","caller":"traceutil/trace.go:171","msg":"trace[1937602294] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"184.969097ms","start":"2026-02-25T15:12:12.059949Z","end":"2026-02-25T15:12:12.244918Z","steps":["trace[1937602294] 'process raft request' (duration: 184.807923ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:12.244958Z","caller":"traceutil/trace.go:171","msg":"trace[2126334190] linearizableReadLoop","detail":"{readStateIndex:5212; appliedIndex:5212; }","duration":"158.158661ms","start":"2026-02-25T15:12:12.086763Z","end":"2026-02-25T15:12:12.244921Z","steps":["trace[2126334190] 'read index received' (duration: 158.147191ms)","trace[2126334190] 'applied index is now lower than readState.Index' (duration: 9.72µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:12:12.245172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.394828ms","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-25T15:12:12.245225Z","caller":"traceutil/trace.go:171","msg":"trace[462824235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5021; }","duration":"158.502921ms","start":"2026-02-25T15:12:12.086711Z","end":"2026-02-25T15:12:12.245214Z","steps":["trace[462824235] 'agreement among raft nodes before linearized reading' (duration: 158.308876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:12.258498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.523846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12015"} {"level":"warn","ts":"2026-02-25T15:12:12.258553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.584453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-24ffd1b8-747f-4979-be47-e10aead94eeb\" ","response":"range_response_count:1 size:3602"} {"level":"info","ts":"2026-02-25T15:12:12.258594Z","caller":"traceutil/trace.go:171","msg":"trace[5082757] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5022; }","duration":"169.65846ms","start":"2026-02-25T15:12:12.088913Z","end":"2026-02-25T15:12:12.258572Z","steps":["trace[5082757] 'agreement among raft nodes before linearized reading' (duration: 169.417734ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:12.258624Z","caller":"traceutil/trace.go:171","msg":"trace[1810292207] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-24ffd1b8-747f-4979-be47-e10aead94eeb; range_end:; response_count:1; response_revision:5022; }","duration":"116.738297ms","start":"2026-02-25T15:12:12.141866Z","end":"2026-02-25T15:12:12.258605Z","steps":["trace[1810292207] 'agreement among raft nodes before linearized reading' (duration: 116.514901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:13.159763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.949762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:12:13.159896Z","caller":"traceutil/trace.go:171","msg":"trace[457269206] linearizableReadLoop","detail":"{readStateIndex:5216; appliedIndex:5215; }","duration":"525.346604ms","start":"2026-02-25T15:12:12.634525Z","end":"2026-02-25T15:12:13.159872Z","steps":["trace[457269206] 'read index received' (duration: 7.307989ms)","trace[457269206] 'applied index is now lower than readState.Index' (duration: 518.036854ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:13.159924Z","caller":"traceutil/trace.go:171","msg":"trace[1651890479] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"602.32513ms","start":"2026-02-25T15:12:12.557546Z","end":"2026-02-25T15:12:13.159871Z","steps":["trace[1651890479] 'process raft request' (duration: 84.160932ms)","trace[1651890479] 'compare' (duration: 517.806369ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:12:13.16003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:12.557517Z","time spent":"602.456433ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:12:13.160086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.542008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-25T15:12:13.160168Z","caller":"traceutil/trace.go:171","msg":"trace[153914842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5025; }","duration":"525.684071ms","start":"2026-02-25T15:12:12.634465Z","end":"2026-02-25T15:12:13.160149Z","steps":["trace[153914842] 'agreement among raft nodes before linearized reading' (duration: 525.488017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:13.160214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:12.63444Z","time spent":"525.765644ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T15:12:13.380903Z","caller":"traceutil/trace.go:171","msg":"trace[1884430975] linearizableReadLoop","detail":"{readStateIndex:5217; appliedIndex:5216; }","duration":"220.94422ms","start":"2026-02-25T15:12:13.159931Z","end":"2026-02-25T15:12:13.380876Z","steps":["trace[1884430975] 'read index received' (duration: 204.523594ms)","trace[1884430975] 'applied index is now lower than readState.Index' (duration: 16.418936ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:13.381059Z","caller":"traceutil/trace.go:171","msg":"trace[308828117] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"464.485486ms","start":"2026-02-25T15:12:12.916528Z","end":"2026-02-25T15:12:13.381013Z","steps":["trace[308828117] 'process raft request' (duration: 447.907536ms)","trace[308828117] 'compare' (duration: 16.170179ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:12:13.38112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.560574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sjlxj\" ","response":"range_response_count:1 size:6386"} {"level":"warn","ts":"2026-02-25T15:12:13.381163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.15136ms","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-25T15:12:13.38119Z","caller":"traceutil/trace.go:171","msg":"trace[989270835] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sjlxj; range_end:; response_count:1; response_revision:5026; }","duration":"745.690537ms","start":"2026-02-25T15:12:12.635483Z","end":"2026-02-25T15:12:13.381173Z","steps":["trace[989270835] 'agreement among raft nodes before linearized reading' (duration: 745.448071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:13.381144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.696318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T15:12:13.381232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:12.63546Z","time spent":"745.764509ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6408,"request content":"key:\"/registry/pods/openstack/keepalived-sjlxj\" "} {"level":"warn","ts":"2026-02-25T15:12:13.381205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.238298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-24ffd1b8-747f-4979-be47-e10aead94eeb\" ","response":"range_response_count:1 size:3602"} {"level":"warn","ts":"2026-02-25T15:12:13.381254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:12.916499Z","time spent":"464.65733ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:12:13.381302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.714694ms","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-25T15:12:13.381305Z","caller":"traceutil/trace.go:171","msg":"trace[571528552] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-24ffd1b8-747f-4979-be47-e10aead94eeb; range_end:; response_count:1; response_revision:5026; }","duration":"629.387692ms","start":"2026-02-25T15:12:12.751886Z","end":"2026-02-25T15:12:13.381273Z","steps":["trace[571528552] 'agreement among raft nodes before linearized reading' (duration: 629.108085ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:13.381333Z","caller":"traceutil/trace.go:171","msg":"trace[1743070124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5026; }","duration":"746.795086ms","start":"2026-02-25T15:12:12.634529Z","end":"2026-02-25T15:12:13.381324Z","steps":["trace[1743070124] 'agreement among raft nodes before linearized reading' (duration: 746.694433ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:13.38127Z","caller":"traceutil/trace.go:171","msg":"trace[2055561722] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:5026; }","duration":"713.881332ms","start":"2026-02-25T15:12:12.667364Z","end":"2026-02-25T15:12:13.381245Z","steps":["trace[2055561722] 'agreement among raft nodes before linearized reading' (duration: 713.543364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:13.381367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:12.634499Z","time spent":"746.860538ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T15:12:13.38137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:12.751859Z","time spent":"629.490644ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-24ffd1b8-747f-4979-be47-e10aead94eeb\" "} {"level":"info","ts":"2026-02-25T15:12:13.381201Z","caller":"traceutil/trace.go:171","msg":"trace[83276284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5026; }","duration":"746.251913ms","start":"2026-02-25T15:12:12.634939Z","end":"2026-02-25T15:12:13.381191Z","steps":["trace[83276284] 'agreement among raft nodes before linearized reading' (duration: 746.068658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:13.381752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:12.667346Z","time spent":"714.088358ms","remote":"127.0.0.1:39450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":75,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-25T15:12:13.381903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:12.634915Z","time spent":"746.870738ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T15:12:13.381172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.991846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:12:13.38218Z","caller":"traceutil/trace.go:171","msg":"trace[28047747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5026; }","duration":"737.036003ms","start":"2026-02-25T15:12:12.645124Z","end":"2026-02-25T15:12:13.38216Z","steps":["trace[28047747] 'agreement among raft nodes before linearized reading' (duration: 735.981386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:13.382227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:12.64511Z","time spent":"737.102806ms","remote":"127.0.0.1:39230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T15:12:13.386838Z","caller":"traceutil/trace.go:171","msg":"trace[1916279379] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"222.429719ms","start":"2026-02-25T15:12:13.164383Z","end":"2026-02-25T15:12:13.386813Z","steps":["trace[1916279379] 'process raft request' (duration: 221.988568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:13.387422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.337609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-24ffd1b8-747f-4979-be47-e10aead94eeb\" ","response":"range_response_count:1 size:3602"} {"level":"info","ts":"2026-02-25T15:12:13.387493Z","caller":"traceutil/trace.go:171","msg":"trace[1509025412] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-24ffd1b8-747f-4979-be47-e10aead94eeb; range_end:; response_count:1; response_revision:5027; }","duration":"123.472752ms","start":"2026-02-25T15:12:13.264006Z","end":"2026-02-25T15:12:13.387479Z","steps":["trace[1509025412] 'agreement among raft nodes before linearized reading' (duration: 123.285947ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:16.586363Z","caller":"traceutil/trace.go:171","msg":"trace[14225924] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"176.137076ms","start":"2026-02-25T15:12:16.410175Z","end":"2026-02-25T15:12:16.586312Z","steps":["trace[14225924] 'process raft request' (duration: 134.486447ms)","trace[14225924] 'compare' (duration: 41.393503ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:24.400881Z","caller":"traceutil/trace.go:171","msg":"trace[1246849917] linearizableReadLoop","detail":"{readStateIndex:5354; appliedIndex:5353; }","duration":"100.820195ms","start":"2026-02-25T15:12:24.300034Z","end":"2026-02-25T15:12:24.400854Z","steps":["trace[1246849917] 'read index received' (duration: 100.528168ms)","trace[1246849917] 'applied index is now lower than readState.Index' (duration: 290.017µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:24.401121Z","caller":"traceutil/trace.go:171","msg":"trace[1422872387] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"105.212199ms","start":"2026-02-25T15:12:24.295858Z","end":"2026-02-25T15:12:24.401071Z","steps":["trace[1422872387] 'process raft request' (duration: 104.809309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:24.401322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.231636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T15:12:24.401447Z","caller":"traceutil/trace.go:171","msg":"trace[973807403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5160; }","duration":"101.417181ms","start":"2026-02-25T15:12:24.299997Z","end":"2026-02-25T15:12:24.401414Z","steps":["trace[973807403] 'agreement among raft nodes before linearized reading' (duration: 101.012281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:27.715325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.988038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12015"} {"level":"info","ts":"2026-02-25T15:12:27.715464Z","caller":"traceutil/trace.go:171","msg":"trace[1939736490] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5213; }","duration":"116.196884ms","start":"2026-02-25T15:12:27.599236Z","end":"2026-02-25T15:12:27.715433Z","steps":["trace[1939736490] 'range keys from in-memory index tree' (duration: 115.690461ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:50.554596Z","caller":"traceutil/trace.go:171","msg":"trace[1306941995] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"168.147032ms","start":"2026-02-25T15:12:50.386421Z","end":"2026-02-25T15:12:50.554568Z","steps":["trace[1306941995] 'process raft request' (duration: 167.933517ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:50.613494Z","caller":"traceutil/trace.go:171","msg":"trace[993283332] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"116.401886ms","start":"2026-02-25T15:12:50.497065Z","end":"2026-02-25T15:12:50.613466Z","steps":["trace[993283332] 'process raft request' (duration: 116.236612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:51.510067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.366022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:12:51.510218Z","caller":"traceutil/trace.go:171","msg":"trace[1668043020] transaction","detail":"{read_only:false; response_revision:5369; number_of_response:1; }","duration":"625.895176ms","start":"2026-02-25T15:12:50.884295Z","end":"2026-02-25T15:12:51.51019Z","steps":["trace[1668043020] 'process raft request' (duration: 429.188715ms)","trace[1668043020] 'compare' (duration: 196.222838ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:12:51.510314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:50.884265Z","time spent":"625.999899ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:12:52.054359Z","caller":"traceutil/trace.go:171","msg":"trace[685175480] linearizableReadLoop","detail":"{readStateIndex:5569; appliedIndex:5567; }","duration":"944.851474ms","start":"2026-02-25T15:12:51.109446Z","end":"2026-02-25T15:12:52.054297Z","steps":["trace[685175480] 'read index received' (duration: 204.03655ms)","trace[685175480] 'applied index is now lower than readState.Index' (duration: 740.812684ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:52.054351Z","caller":"traceutil/trace.go:171","msg":"trace[1097270201] transaction","detail":"{read_only:false; response_revision:5370; number_of_response:1; }","duration":"997.659649ms","start":"2026-02-25T15:12:51.056657Z","end":"2026-02-25T15:12:52.054316Z","steps":["trace[1097270201] 'process raft request' (duration: 995.666357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:52.054808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.347018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-gateway-6b458d8465-km789\" ","response":"range_response_count:1 size:4955"} {"level":"info","ts":"2026-02-25T15:12:52.054881Z","caller":"traceutil/trace.go:171","msg":"trace[777876123] range","detail":"{range_begin:/registry/pods/monitoring/loki-gateway-6b458d8465-km789; range_end:; response_count:1; response_revision:5370; }","duration":"945.463191ms","start":"2026-02-25T15:12:51.1094Z","end":"2026-02-25T15:12:52.054863Z","steps":["trace[777876123] 'agreement among raft nodes before linearized reading' (duration: 945.244405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:52.05493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:51.109385Z","time spent":"945.531112ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4977,"request content":"key:\"/registry/pods/monitoring/loki-gateway-6b458d8465-km789\" "} {"level":"warn","ts":"2026-02-25T15:12:52.055234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:51.05663Z","time spent":"998.52428ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:12:52.059384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.140273ms","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-25T15:12:52.059416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.321705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sjlxj\" ","response":"range_response_count:1 size:6386"} {"level":"warn","ts":"2026-02-25T15:12:52.059408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.626632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/loki-gateway-6b458d8465-km789.189785ff1f88d073\" ","response":"range_response_count:1 size:788"} {"level":"info","ts":"2026-02-25T15:12:52.059454Z","caller":"traceutil/trace.go:171","msg":"trace[823539967] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sjlxj; range_end:; response_count:1; response_revision:5371; }","duration":"370.394816ms","start":"2026-02-25T15:12:51.689048Z","end":"2026-02-25T15:12:52.059442Z","steps":["trace[823539967] 'agreement among raft nodes before linearized reading' (duration: 370.303794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:52.059462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.824523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-25T15:12:52.059486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:51.689029Z","time spent":"370.449037ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6408,"request content":"key:\"/registry/pods/openstack/keepalived-sjlxj\" "} {"level":"info","ts":"2026-02-25T15:12:52.059492Z","caller":"traceutil/trace.go:171","msg":"trace[943993663] range","detail":"{range_begin:/registry/events/monitoring/loki-gateway-6b458d8465-km789.189785ff1f88d073; range_end:; response_count:1; response_revision:5371; }","duration":"948.747575ms","start":"2026-02-25T15:12:51.110708Z","end":"2026-02-25T15:12:52.059456Z","steps":["trace[943993663] 'agreement among raft nodes before linearized reading' (duration: 948.53274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:52.059489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.239581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T15:12:52.059551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:51.110685Z","time spent":"948.852957ms","remote":"127.0.0.1:39310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":810,"request content":"key:\"/registry/events/monitoring/loki-gateway-6b458d8465-km789.189785ff1f88d073\" "} {"level":"warn","ts":"2026-02-25T15:12:52.059577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.59516ms","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-25T15:12:52.059596Z","caller":"traceutil/trace.go:171","msg":"trace[955839464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5371; }","duration":"193.61277ms","start":"2026-02-25T15:12:51.865977Z","end":"2026-02-25T15:12:52.05959Z","steps":["trace[955839464] 'agreement among raft nodes before linearized reading' (duration: 193.569448ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:52.059593Z","caller":"traceutil/trace.go:171","msg":"trace[604790621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5371; }","duration":"411.376636ms","start":"2026-02-25T15:12:51.648192Z","end":"2026-02-25T15:12:52.059569Z","steps":["trace[604790621] 'agreement among raft nodes before linearized reading' (duration: 411.229871ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:52.059445Z","caller":"traceutil/trace.go:171","msg":"trace[367052644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5371; }","duration":"218.240345ms","start":"2026-02-25T15:12:51.841189Z","end":"2026-02-25T15:12:52.059429Z","steps":["trace[367052644] 'agreement among raft nodes before linearized reading' (duration: 218.069351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:52.059521Z","caller":"traceutil/trace.go:171","msg":"trace[849986936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5371; }","duration":"371.929326ms","start":"2026-02-25T15:12:51.687577Z","end":"2026-02-25T15:12:52.059506Z","steps":["trace[849986936] 'agreement among raft nodes before linearized reading' (duration: 371.748472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:52.059775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:51.687557Z","time spent":"372.204943ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T15:12:52.059645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:51.648172Z","time spent":"411.459587ms","remote":"127.0.0.1:39230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T15:12:52.060198Z","caller":"traceutil/trace.go:171","msg":"trace[125012552] transaction","detail":"{read_only:false; response_revision:5371; number_of_response:1; }","duration":"675.686872ms","start":"2026-02-25T15:12:51.384472Z","end":"2026-02-25T15:12:52.060159Z","steps":["trace[125012552] 'process raft request' (duration: 674.613245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:52.060696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:51.384445Z","time spent":"675.857027ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:12:52.220729Z","caller":"traceutil/trace.go:171","msg":"trace[421758617] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"157.091548ms","start":"2026-02-25T15:12:52.063611Z","end":"2026-02-25T15:12:52.220703Z","steps":["trace[421758617] 'process raft request' (duration: 120.059312ms)","trace[421758617] 'compare' (duration: 36.82203ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:52.220747Z","caller":"traceutil/trace.go:171","msg":"trace[1257091832] transaction","detail":"{read_only:false; response_revision:5374; number_of_response:1; }","duration":"157.052927ms","start":"2026-02-25T15:12:52.063672Z","end":"2026-02-25T15:12:52.220725Z","steps":["trace[1257091832] 'process raft request' (duration: 156.941744ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:52.221035Z","caller":"traceutil/trace.go:171","msg":"trace[747899797] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"157.300733ms","start":"2026-02-25T15:12:52.063702Z","end":"2026-02-25T15:12:52.221003Z","steps":["trace[747899797] 'process raft request' (duration: 156.964934ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:52.221094Z","caller":"traceutil/trace.go:171","msg":"trace[1053530707] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"156.408029ms","start":"2026-02-25T15:12:52.064676Z","end":"2026-02-25T15:12:52.221084Z","steps":["trace[1053530707] 'process raft request' (duration: 156.258386ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:52.221072Z","caller":"traceutil/trace.go:171","msg":"trace[290461048] linearizableReadLoop","detail":"{readStateIndex:5574; appliedIndex:5570; }","duration":"157.263561ms","start":"2026-02-25T15:12:52.063784Z","end":"2026-02-25T15:12:52.221048Z","steps":["trace[290461048] 'read index received' (duration: 27.391397ms)","trace[290461048] 'applied index is now lower than readState.Index' (duration: 129.871194ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:12:52.221301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.532908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/loki-results-cache-0.189785ff35417901\" ","response":"range_response_count:1 size:750"} {"level":"info","ts":"2026-02-25T15:12:52.221345Z","caller":"traceutil/trace.go:171","msg":"trace[1449884451] range","detail":"{range_begin:/registry/events/monitoring/loki-results-cache-0.189785ff35417901; range_end:; response_count:1; response_revision:5376; }","duration":"126.61547ms","start":"2026-02-25T15:12:52.094715Z","end":"2026-02-25T15:12:52.22133Z","steps":["trace[1449884451] 'agreement among raft nodes before linearized reading' (duration: 126.466316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:52.221439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.634222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80506"} {"level":"info","ts":"2026-02-25T15:12:52.221503Z","caller":"traceutil/trace.go:171","msg":"trace[1148143132] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5376; }","duration":"157.723983ms","start":"2026-02-25T15:12:52.063765Z","end":"2026-02-25T15:12:52.221489Z","steps":["trace[1148143132] 'agreement among raft nodes before linearized reading' (duration: 157.398984ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:53.316792Z","caller":"traceutil/trace.go:171","msg":"trace[1705591447] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"187.002399ms","start":"2026-02-25T15:12:53.129758Z","end":"2026-02-25T15:12:53.31676Z","steps":["trace[1705591447] 'process raft request' (duration: 143.046024ms)","trace[1705591447] 'compare' (duration: 43.801311ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:53.321684Z","caller":"traceutil/trace.go:171","msg":"trace[1341464539] linearizableReadLoop","detail":"{readStateIndex:5584; appliedIndex:5582; }","duration":"176.645222ms","start":"2026-02-25T15:12:53.145009Z","end":"2026-02-25T15:12:53.321654Z","steps":["trace[1341464539] 'read index received' (duration: 127.745259ms)","trace[1341464539] 'applied index is now lower than readState.Index' (duration: 48.898773ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:53.321706Z","caller":"traceutil/trace.go:171","msg":"trace[1155147596] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"189.434802ms","start":"2026-02-25T15:12:53.132244Z","end":"2026-02-25T15:12:53.321679Z","steps":["trace[1155147596] 'process raft request' (duration: 189.181276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:53.322049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.491339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12015"} {"level":"warn","ts":"2026-02-25T15:12:53.322063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.038093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-02-25T15:12:53.322119Z","caller":"traceutil/trace.go:171","msg":"trace[477974424] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5385; }","duration":"164.604892ms","start":"2026-02-25T15:12:53.157497Z","end":"2026-02-25T15:12:53.322102Z","steps":["trace[477974424] 'agreement among raft nodes before linearized reading' (duration: 164.319724ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:53.322141Z","caller":"traceutil/trace.go:171","msg":"trace[180828502] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5385; }","duration":"177.153995ms","start":"2026-02-25T15:12:53.144964Z","end":"2026-02-25T15:12:53.322118Z","steps":["trace[180828502] 'agreement among raft nodes before linearized reading' (duration: 176.837246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:53.322052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.823541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:12:53.322276Z","caller":"traceutil/trace.go:171","msg":"trace[358219423] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5385; }","duration":"145.082587ms","start":"2026-02-25T15:12:53.177168Z","end":"2026-02-25T15:12:53.322251Z","steps":["trace[358219423] 'agreement among raft nodes before linearized reading' (duration: 144.813331ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:53.544701Z","caller":"traceutil/trace.go:171","msg":"trace[1938504238] linearizableReadLoop","detail":"{readStateIndex:5586; appliedIndex:5585; }","duration":"133.845087ms","start":"2026-02-25T15:12:53.410813Z","end":"2026-02-25T15:12:53.544659Z","steps":["trace[1938504238] 'read index received' (duration: 39.818367ms)","trace[1938504238] 'applied index is now lower than readState.Index' (duration: 94.02421ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:53.544739Z","caller":"traceutil/trace.go:171","msg":"trace[88020901] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"180.717367ms","start":"2026-02-25T15:12:53.363987Z","end":"2026-02-25T15:12:53.544704Z","steps":["trace[88020901] 'process raft request' (duration: 86.685428ms)","trace[88020901] 'compare' (duration: 93.727541ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:12:53.544999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.150834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:12:53.545057Z","caller":"traceutil/trace.go:171","msg":"trace[1593427977] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5388; }","duration":"134.298708ms","start":"2026-02-25T15:12:53.410745Z","end":"2026-02-25T15:12:53.545044Z","steps":["trace[1593427977] 'agreement among raft nodes before linearized reading' (duration: 134.056152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:54.001051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.116827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T15:12:54.001151Z","caller":"traceutil/trace.go:171","msg":"trace[1344165026] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:5389; }","duration":"119.261211ms","start":"2026-02-25T15:12:53.881872Z","end":"2026-02-25T15:12:54.001133Z","steps":["trace[1344165026] 'range keys from in-memory index tree' (duration: 119.043955ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:54.200018Z","caller":"traceutil/trace.go:171","msg":"trace[598425757] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"134.020723ms","start":"2026-02-25T15:12:54.065977Z","end":"2026-02-25T15:12:54.199998Z","steps":["trace[598425757] 'process raft request' (duration: 133.89666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:54.373712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:12:54.068665Z","time spent":"305.039869ms","remote":"127.0.0.1:39310","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T15:12:54.373997Z","caller":"traceutil/trace.go:171","msg":"trace[282080621] linearizableReadLoop","detail":"{readStateIndex:5592; appliedIndex:5591; }","duration":"228.047509ms","start":"2026-02-25T15:12:54.145922Z","end":"2026-02-25T15:12:54.373969Z","steps":["trace[282080621] 'read index received' (duration: 54.095497ms)","trace[282080621] 'applied index is now lower than readState.Index' (duration: 173.950662ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:12:54.374194Z","caller":"traceutil/trace.go:171","msg":"trace[1543291169] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"212.294453ms","start":"2026-02-25T15:12:54.161856Z","end":"2026-02-25T15:12:54.374151Z","steps":["trace[1543291169] 'process raft request' (duration: 211.677237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:54.374256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.301546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T15:12:54.374351Z","caller":"traceutil/trace.go:171","msg":"trace[1322828397] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5392; }","duration":"228.477361ms","start":"2026-02-25T15:12:54.145852Z","end":"2026-02-25T15:12:54.374329Z","steps":["trace[1322828397] 'agreement among raft nodes before linearized reading' (duration: 228.291096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:54.374399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.206548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-02-25T15:12:54.374705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.439688ms","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-25T15:12:54.374767Z","caller":"traceutil/trace.go:171","msg":"trace[335906906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5392; }","duration":"147.56751ms","start":"2026-02-25T15:12:54.227184Z","end":"2026-02-25T15:12:54.374752Z","steps":["trace[335906906] 'agreement among raft nodes before linearized reading' (duration: 147.206071ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:54.374784Z","caller":"traceutil/trace.go:171","msg":"trace[1096488951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5392; }","duration":"148.607108ms","start":"2026-02-25T15:12:54.226134Z","end":"2026-02-25T15:12:54.374741Z","steps":["trace[1096488951] 'agreement among raft nodes before linearized reading' (duration: 148.072255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:54.37464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.371812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-25T15:12:54.375299Z","caller":"traceutil/trace.go:171","msg":"trace[1367468233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5392; }","duration":"149.07226ms","start":"2026-02-25T15:12:54.226206Z","end":"2026-02-25T15:12:54.375278Z","steps":["trace[1367468233] 'agreement among raft nodes before linearized reading' (duration: 148.240169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:12:54.374624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.367506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sjlxj\" ","response":"range_response_count:1 size:6386"} {"level":"info","ts":"2026-02-25T15:12:54.375403Z","caller":"traceutil/trace.go:171","msg":"trace[1063193779] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sjlxj; range_end:; response_count:1; response_revision:5392; }","duration":"148.200217ms","start":"2026-02-25T15:12:54.227189Z","end":"2026-02-25T15:12:54.375389Z","steps":["trace[1063193779] 'agreement among raft nodes before linearized reading' (duration: 147.056288ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:12:54.741572Z","caller":"traceutil/trace.go:171","msg":"trace[1138551611] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"104.703093ms","start":"2026-02-25T15:12:54.636832Z","end":"2026-02-25T15:12:54.741535Z","steps":["trace[1138551611] 'process raft request' (duration: 34.018488ms)","trace[1138551611] 'compare' (duration: 70.510761ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:20.24861Z","caller":"traceutil/trace.go:171","msg":"trace[1002090185] linearizableReadLoop","detail":"{readStateIndex:5780; appliedIndex:5779; }","duration":"125.043349ms","start":"2026-02-25T15:13:20.12354Z","end":"2026-02-25T15:13:20.248583Z","steps":["trace[1002090185] 'read index received' (duration: 68.539489ms)","trace[1002090185] 'applied index is now lower than readState.Index' (duration: 56.50203ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:20.248742Z","caller":"traceutil/trace.go:171","msg":"trace[1211341187] transaction","detail":"{read_only:false; response_revision:5574; number_of_response:1; }","duration":"128.55157ms","start":"2026-02-25T15:13:20.120154Z","end":"2026-02-25T15:13:20.248705Z","steps":["trace[1211341187] 'process raft request' (duration: 71.960668ms)","trace[1211341187] 'compare' (duration: 56.321435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:13:20.24905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.490081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone-server-0.18978607e7ab2411\" ","response":"range_response_count:1 size:749"} {"level":"info","ts":"2026-02-25T15:13:20.249208Z","caller":"traceutil/trace.go:171","msg":"trace[1338981666] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone-server-0.18978607e7ab2411; range_end:; response_count:1; response_revision:5574; }","duration":"125.646415ms","start":"2026-02-25T15:13:20.123485Z","end":"2026-02-25T15:13:20.249132Z","steps":["trace[1338981666] 'agreement among raft nodes before linearized reading' (duration: 125.312796ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:26.924796Z","caller":"traceutil/trace.go:171","msg":"trace[1666061340] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"109.352364ms","start":"2026-02-25T15:13:26.815427Z","end":"2026-02-25T15:13:26.924779Z","steps":["trace[1666061340] 'process raft request' (duration: 109.250881ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:27.022123Z","caller":"traceutil/trace.go:171","msg":"trace[948771514] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"148.073734ms","start":"2026-02-25T15:13:26.874013Z","end":"2026-02-25T15:13:27.022087Z","steps":["trace[948771514] 'process raft request' (duration: 116.658443ms)","trace[948771514] 'compare' (duration: 31.271638ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:30.82994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2765} {"level":"info","ts":"2026-02-25T15:13:31.072161Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2765,"took":"241.064495ms","hash":2034617967,"current-db-size-bytes":40116224,"current-db-size":"40 MB","current-db-size-in-use-bytes":29626368,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-02-25T15:13:31.07222Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2034617967,"revision":2765,"compact-revision":-1} {"level":"warn","ts":"2026-02-25T15:13:47.018632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.053259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:13:47.018803Z","caller":"traceutil/trace.go:171","msg":"trace[2034680108] linearizableReadLoop","detail":"{readStateIndex:5956; appliedIndex:5955; }","duration":"345.252464ms","start":"2026-02-25T15:13:46.673527Z","end":"2026-02-25T15:13:47.018779Z","steps":["trace[2034680108] 'read index received' (duration: 53.111µs)","trace[2034680108] 'applied index is now lower than readState.Index' (duration: 345.197633ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:47.018861Z","caller":"traceutil/trace.go:171","msg":"trace[457126787] transaction","detail":"{read_only:false; response_revision:5744; number_of_response:1; }","duration":"549.149219ms","start":"2026-02-25T15:13:46.46967Z","end":"2026-02-25T15:13:47.018819Z","steps":["trace[457126787] 'process raft request' (duration: 100.796043ms)","trace[457126787] 'compare' (duration: 447.748191ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:13:47.019058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:46.469643Z","time spent":"549.314104ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:13:47.019058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.51759ms","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-25T15:13:47.019143Z","caller":"traceutil/trace.go:171","msg":"trace[960158689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5744; }","duration":"345.662936ms","start":"2026-02-25T15:13:46.673459Z","end":"2026-02-25T15:13:47.019122Z","steps":["trace[960158689] 'agreement among raft nodes before linearized reading' (duration: 345.430189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:47.01915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.183967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-25T15:13:47.019201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:46.673433Z","time spent":"345.756987ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T15:13:47.019215Z","caller":"traceutil/trace.go:171","msg":"trace[1905334333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5744; }","duration":"239.29872ms","start":"2026-02-25T15:13:46.779898Z","end":"2026-02-25T15:13:47.019196Z","steps":["trace[1905334333] 'agreement among raft nodes before linearized reading' (duration: 239.096004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:47.019206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.564473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T15:13:47.019338Z","caller":"traceutil/trace.go:171","msg":"trace[626190454] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:5744; }","duration":"224.753878ms","start":"2026-02-25T15:13:46.794568Z","end":"2026-02-25T15:13:47.019322Z","steps":["trace[626190454] 'agreement among raft nodes before linearized reading' (duration: 224.516162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:47.019332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.455437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sjlxj\" ","response":"range_response_count:1 size:6386"} {"level":"warn","ts":"2026-02-25T15:13:47.019418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.360326ms","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-25T15:13:47.019616Z","caller":"traceutil/trace.go:171","msg":"trace[190555423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5744; }","duration":"238.591842ms","start":"2026-02-25T15:13:46.780959Z","end":"2026-02-25T15:13:47.01955Z","steps":["trace[190555423] 'agreement among raft nodes before linearized reading' (duration: 238.064118ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:47.019466Z","caller":"traceutil/trace.go:171","msg":"trace[2020013500] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sjlxj; range_end:; response_count:1; response_revision:5744; }","duration":"236.6018ms","start":"2026-02-25T15:13:46.782834Z","end":"2026-02-25T15:13:47.019436Z","steps":["trace[2020013500] 'agreement among raft nodes before linearized reading' (duration: 236.165949ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:47.892119Z","caller":"traceutil/trace.go:171","msg":"trace[1835992646] transaction","detail":"{read_only:false; response_revision:5753; number_of_response:1; }","duration":"372.392288ms","start":"2026-02-25T15:13:47.519697Z","end":"2026-02-25T15:13:47.89209Z","steps":["trace[1835992646] 'process raft request' (duration: 312.002375ms)","trace[1835992646] 'compare' (duration: 60.091204ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:47.892198Z","caller":"traceutil/trace.go:171","msg":"trace[418587273] transaction","detail":"{read_only:false; response_revision:5755; number_of_response:1; }","duration":"371.754221ms","start":"2026-02-25T15:13:47.520436Z","end":"2026-02-25T15:13:47.89219Z","steps":["trace[418587273] 'process raft request' (duration: 371.578506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:47.892324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:47.520431Z","time spent":"371.803122ms","remote":"127.0.0.1:39510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1668,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:13:47.892327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:47.519685Z","time spent":"372.5198ms","remote":"127.0.0.1:39510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1776,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:13:47.892137Z","caller":"traceutil/trace.go:171","msg":"trace[494112309] transaction","detail":"{read_only:false; response_revision:5754; number_of_response:1; }","duration":"371.779262ms","start":"2026-02-25T15:13:47.520332Z","end":"2026-02-25T15:13:47.892112Z","steps":["trace[494112309] 'process raft request' (duration: 371.583526ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:47.892548Z","caller":"traceutil/trace.go:171","msg":"trace[1288085606] linearizableReadLoop","detail":"{readStateIndex:5969; appliedIndex:5964; }","duration":"244.080071ms","start":"2026-02-25T15:13:47.648445Z","end":"2026-02-25T15:13:47.892525Z","steps":["trace[1288085606] 'read index received' (duration: 183.307099ms)","trace[1288085606] 'applied index is now lower than readState.Index' (duration: 60.771942ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:47.89259Z","caller":"traceutil/trace.go:171","msg":"trace[1149808140] transaction","detail":"{read_only:false; response_revision:5757; number_of_response:1; }","duration":"368.877078ms","start":"2026-02-25T15:13:47.523687Z","end":"2026-02-25T15:13:47.892564Z","steps":["trace[1149808140] 'process raft request' (duration: 368.633552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:47.892657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.210154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:13:47.892699Z","caller":"traceutil/trace.go:171","msg":"trace[838080736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5757; }","duration":"244.286677ms","start":"2026-02-25T15:13:47.648401Z","end":"2026-02-25T15:13:47.892688Z","steps":["trace[838080736] 'agreement among raft nodes before linearized reading' (duration: 244.204075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:47.892698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:47.523658Z","time spent":"368.98292ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3829,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:13:47.892998Z","caller":"traceutil/trace.go:171","msg":"trace[2077635998] transaction","detail":"{read_only:false; response_revision:5756; number_of_response:1; }","duration":"371.392802ms","start":"2026-02-25T15:13:47.521105Z","end":"2026-02-25T15:13:47.892497Z","steps":["trace[2077635998] 'process raft request' (duration: 370.95279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:47.893309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:47.521098Z","time spent":"371.933965ms","remote":"127.0.0.1:39410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1485,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:13:47.893998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:47.520325Z","time spent":"372.143091ms","remote":"127.0.0.1:39536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1409,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:13:49.276495Z","caller":"traceutil/trace.go:171","msg":"trace[1469457928] linearizableReadLoop","detail":"{readStateIndex:5972; appliedIndex:5971; }","duration":"224.83658ms","start":"2026-02-25T15:13:49.051632Z","end":"2026-02-25T15:13:49.276468Z","steps":["trace[1469457928] 'read index received' (duration: 224.679176ms)","trace[1469457928] 'applied index is now lower than readState.Index' (duration: 156.484µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:49.276585Z","caller":"traceutil/trace.go:171","msg":"trace[1599580439] transaction","detail":"{read_only:false; response_revision:5760; number_of_response:1; }","duration":"251.002528ms","start":"2026-02-25T15:13:49.025552Z","end":"2026-02-25T15:13:49.276555Z","steps":["trace[1599580439] 'process raft request' (duration: 250.763062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:49.276699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.046315ms","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-25T15:13:49.276739Z","caller":"traceutil/trace.go:171","msg":"trace[1335216934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5760; }","duration":"225.133257ms","start":"2026-02-25T15:13:49.051595Z","end":"2026-02-25T15:13:49.276728Z","steps":["trace[1335216934] 'agreement among raft nodes before linearized reading' (duration: 224.991124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:49.465299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.267064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:13:49.465499Z","caller":"traceutil/trace.go:171","msg":"trace[269580835] transaction","detail":"{read_only:false; response_revision:5761; number_of_response:1; }","duration":"298.07151ms","start":"2026-02-25T15:13:49.167394Z","end":"2026-02-25T15:13:49.465466Z","steps":["trace[269580835] 'process raft request' (duration: 162.56021ms)","trace[269580835] 'compare' (duration: 135.005237ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:49.473389Z","caller":"traceutil/trace.go:171","msg":"trace[1737399478] linearizableReadLoop","detail":"{readStateIndex:5974; appliedIndex:5972; }","duration":"196.794065ms","start":"2026-02-25T15:13:49.276565Z","end":"2026-02-25T15:13:49.473359Z","steps":["trace[1737399478] 'read index received' (duration: 53.386353ms)","trace[1737399478] 'applied index is now lower than readState.Index' (duration: 143.404722ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:13:49.473522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.486827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sjlxj\" ","response":"range_response_count:1 size:6386"} {"level":"info","ts":"2026-02-25T15:13:49.473581Z","caller":"traceutil/trace.go:171","msg":"trace[1845021928] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sjlxj; range_end:; response_count:1; response_revision:5762; }","duration":"397.5687ms","start":"2026-02-25T15:13:49.075999Z","end":"2026-02-25T15:13:49.473568Z","steps":["trace[1845021928] 'agreement among raft nodes before linearized reading' (duration: 397.407156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:49.473625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.075993Z","time spent":"397.622442ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6408,"request content":"key:\"/registry/pods/openstack/keepalived-sjlxj\" "} {"level":"warn","ts":"2026-02-25T15:13:49.47367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.172787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-25T15:13:49.473764Z","caller":"traceutil/trace.go:171","msg":"trace[1281240242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5762; }","duration":"400.29812ms","start":"2026-02-25T15:13:49.073447Z","end":"2026-02-25T15:13:49.473745Z","steps":["trace[1281240242] 'agreement among raft nodes before linearized reading' (duration: 400.019222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:49.473812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.07344Z","time spent":"400.362241ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T15:13:49.47389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.805063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/prometheus-pushgateway-f5d6d8b68-zv95t.18978600cd1653b2\" ","response":"range_response_count:1 size:806"} {"level":"info","ts":"2026-02-25T15:13:49.473352Z","caller":"traceutil/trace.go:171","msg":"trace[2110914648] transaction","detail":"{read_only:false; response_revision:5762; number_of_response:1; }","duration":"192.383372ms","start":"2026-02-25T15:13:49.280949Z","end":"2026-02-25T15:13:49.473333Z","steps":["trace[2110914648] 'process raft request' (duration: 192.272109ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:49.473957Z","caller":"traceutil/trace.go:171","msg":"trace[955749841] range","detail":"{range_begin:/registry/events/monitoring/prometheus-pushgateway-f5d6d8b68-zv95t.18978600cd1653b2; range_end:; response_count:1; response_revision:5762; }","duration":"363.090871ms","start":"2026-02-25T15:13:49.110853Z","end":"2026-02-25T15:13:49.473944Z","steps":["trace[955749841] 'agreement among raft nodes before linearized reading' (duration: 362.958338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:49.473989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.110838Z","time spent":"363.142562ms","remote":"127.0.0.1:39310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":828,"request content":"key:\"/registry/events/monitoring/prometheus-pushgateway-f5d6d8b68-zv95t.18978600cd1653b2\" "} {"level":"warn","ts":"2026-02-25T15:13:49.473811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.179462ms","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-25T15:13:49.474112Z","caller":"traceutil/trace.go:171","msg":"trace[1632409956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5762; }","duration":"401.532951ms","start":"2026-02-25T15:13:49.072553Z","end":"2026-02-25T15:13:49.474086Z","steps":["trace[1632409956] 'agreement among raft nodes before linearized reading' (duration: 400.979397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:49.474201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.072535Z","time spent":"401.632473ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T15:13:49.707739Z","caller":"traceutil/trace.go:171","msg":"trace[1586532364] transaction","detail":"{read_only:false; response_revision:5763; number_of_response:1; }","duration":"230.401062ms","start":"2026-02-25T15:13:49.477311Z","end":"2026-02-25T15:13:49.707713Z","steps":["trace[1586532364] 'process raft request' (duration: 132.679677ms)","trace[1586532364] 'compare' (duration: 97.51522ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:49.933987Z","caller":"traceutil/trace.go:171","msg":"trace[2013515748] transaction","detail":"{read_only:false; response_revision:5765; number_of_response:1; }","duration":"456.30415ms","start":"2026-02-25T15:13:49.477661Z","end":"2026-02-25T15:13:49.933965Z","steps":["trace[2013515748] 'process raft request' (duration: 456.164737ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:49.934045Z","caller":"traceutil/trace.go:171","msg":"trace[670012953] linearizableReadLoop","detail":"{readStateIndex:5980; appliedIndex:5975; }","duration":"453.394065ms","start":"2026-02-25T15:13:49.480633Z","end":"2026-02-25T15:13:49.934027Z","steps":["trace[670012953] 'read index received' (duration: 129.368102ms)","trace[670012953] 'applied index is now lower than readState.Index' (duration: 324.025063ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:13:49.934103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.477655Z","time spent":"456.384122ms","remote":"127.0.0.1:39310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":791,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:13:49.934191Z","caller":"traceutil/trace.go:171","msg":"trace[777283389] transaction","detail":"{read_only:false; response_revision:5764; number_of_response:1; }","duration":"456.569726ms","start":"2026-02-25T15:13:49.477593Z","end":"2026-02-25T15:13:49.934162Z","steps":["trace[777283389] 'process raft request' (duration: 376.870872ms)","trace[777283389] 'compare' (duration: 79.09289ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:49.934315Z","caller":"traceutil/trace.go:171","msg":"trace[114270760] transaction","detail":"{read_only:false; response_revision:5766; number_of_response:1; }","duration":"456.331671ms","start":"2026-02-25T15:13:49.477945Z","end":"2026-02-25T15:13:49.934277Z","steps":["trace[114270760] 'process raft request' (duration: 455.979671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:49.934386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.477574Z","time spent":"456.68341ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:13:49.934403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.477938Z","time spent":"456.409813ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:13:49.934468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.837437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80626"} {"level":"info","ts":"2026-02-25T15:13:49.934444Z","caller":"traceutil/trace.go:171","msg":"trace[45064065] transaction","detail":"{read_only:false; response_revision:5767; number_of_response:1; }","duration":"455.954502ms","start":"2026-02-25T15:13:49.478461Z","end":"2026-02-25T15:13:49.934415Z","steps":["trace[45064065] 'process raft request' (duration: 455.49318ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:49.9345Z","caller":"traceutil/trace.go:171","msg":"trace[1839179582] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5768; }","duration":"453.900248ms","start":"2026-02-25T15:13:49.480592Z","end":"2026-02-25T15:13:49.934492Z","steps":["trace[1839179582] 'agreement among raft nodes before linearized reading' (duration: 453.729794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:49.934483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.519045ms","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-25T15:13:49.934493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.796524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:13:49.934525Z","caller":"traceutil/trace.go:171","msg":"trace[255054482] transaction","detail":"{read_only:false; response_revision:5768; number_of_response:1; }","duration":"452.683308ms","start":"2026-02-25T15:13:49.481827Z","end":"2026-02-25T15:13:49.934511Z","steps":["trace[255054482] 'process raft request' (duration: 452.159874ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:49.934548Z","caller":"traceutil/trace.go:171","msg":"trace[1762620913] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:5768; }","duration":"418.639858ms","start":"2026-02-25T15:13:49.515892Z","end":"2026-02-25T15:13:49.934532Z","steps":["trace[1762620913] 'agreement among raft nodes before linearized reading' (duration: 418.516155ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:49.934563Z","caller":"traceutil/trace.go:171","msg":"trace[440417813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5768; }","duration":"288.893486ms","start":"2026-02-25T15:13:49.645652Z","end":"2026-02-25T15:13:49.934546Z","steps":["trace[440417813] 'agreement among raft nodes before linearized reading' (duration: 288.798194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:49.934585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.985509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12015"} {"level":"warn","ts":"2026-02-25T15:13:49.934587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.515868Z","time spent":"418.70847ms","remote":"127.0.0.1:39700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":19,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-25T15:13:49.934593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.478455Z","time spent":"456.063045ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:13:49.934614Z","caller":"traceutil/trace.go:171","msg":"trace[217458441] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5768; }","duration":"347.01322ms","start":"2026-02-25T15:13:49.587595Z","end":"2026-02-25T15:13:49.934608Z","steps":["trace[217458441] 'agreement among raft nodes before linearized reading' (duration: 346.947438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:49.934784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.587549Z","time spent":"347.218335ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12037,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-02-25T15:13:49.934524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.480577Z","time spent":"453.94127ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":80648,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-02-25T15:13:49.934606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.481803Z","time spent":"452.7626ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8231,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:13:50.223499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.619426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:13:50.223701Z","caller":"traceutil/trace.go:171","msg":"trace[1036302257] transaction","detail":"{read_only:false; response_revision:5769; number_of_response:1; }","duration":"275.045862ms","start":"2026-02-25T15:13:49.948633Z","end":"2026-02-25T15:13:50.223678Z","steps":["trace[1036302257] 'process raft request' (duration: 144.153251ms)","trace[1036302257] 'compare' (duration: 130.489581ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:50.317871Z","caller":"traceutil/trace.go:171","msg":"trace[963484624] linearizableReadLoop","detail":"{readStateIndex:5983; appliedIndex:5981; }","duration":"321.9932ms","start":"2026-02-25T15:13:49.995853Z","end":"2026-02-25T15:13:50.317846Z","steps":["trace[963484624] 'read index received' (duration: 97.052577ms)","trace[963484624] 'applied index is now lower than readState.Index' (duration: 224.939843ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:13:50.318033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.153475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:13:50.318072Z","caller":"traceutil/trace.go:171","msg":"trace[290863892] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5770; }","duration":"322.249738ms","start":"2026-02-25T15:13:49.995812Z","end":"2026-02-25T15:13:50.318061Z","steps":["trace[290863892] 'agreement among raft nodes before linearized reading' (duration: 322.158496ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:50.317992Z","caller":"traceutil/trace.go:171","msg":"trace[1772319534] transaction","detail":"{read_only:false; response_revision:5770; number_of_response:1; }","duration":"345.365059ms","start":"2026-02-25T15:13:49.972582Z","end":"2026-02-25T15:13:50.317947Z","steps":["trace[1772319534] 'process raft request' (duration: 345.05401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:50.318113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.995792Z","time spent":"322.313339ms","remote":"127.0.0.1:39700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-02-25T15:13:50.318231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:49.972564Z","time spent":"345.571893ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:13:50.492663Z","caller":"traceutil/trace.go:171","msg":"trace[1730059171] transaction","detail":"{read_only:false; response_revision:5772; number_of_response:1; }","duration":"262.704437ms","start":"2026-02-25T15:13:50.229939Z","end":"2026-02-25T15:13:50.492644Z","steps":["trace[1730059171] 'process raft request' (duration: 262.629885ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:50.492707Z","caller":"traceutil/trace.go:171","msg":"trace[1997315990] transaction","detail":"{read_only:false; response_revision:5771; number_of_response:1; }","duration":"263.018015ms","start":"2026-02-25T15:13:50.229643Z","end":"2026-02-25T15:13:50.492661Z","steps":["trace[1997315990] 'process raft request' (duration: 262.79312ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:50.493051Z","caller":"traceutil/trace.go:171","msg":"trace[189392349] transaction","detail":"{read_only:false; response_revision:5773; number_of_response:1; }","duration":"262.954444ms","start":"2026-02-25T15:13:50.230078Z","end":"2026-02-25T15:13:50.493032Z","steps":["trace[189392349] 'process raft request' (duration: 262.536343ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:50.493154Z","caller":"traceutil/trace.go:171","msg":"trace[1528665594] transaction","detail":"{read_only:false; response_revision:5775; number_of_response:1; }","duration":"258.744136ms","start":"2026-02-25T15:13:50.234401Z","end":"2026-02-25T15:13:50.493145Z","steps":["trace[1528665594] 'process raft request' (duration: 258.589882ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:50.493139Z","caller":"traceutil/trace.go:171","msg":"trace[2069681629] linearizableReadLoop","detail":"{readStateIndex:5988; appliedIndex:5983; }","duration":"171.979761ms","start":"2026-02-25T15:13:50.321136Z","end":"2026-02-25T15:13:50.493115Z","steps":["trace[2069681629] 'read index received' (duration: 171.268833ms)","trace[2069681629] 'applied index is now lower than readState.Index' (duration: 710.138µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:50.493161Z","caller":"traceutil/trace.go:171","msg":"trace[1018265330] transaction","detail":"{read_only:false; response_revision:5774; number_of_response:1; }","duration":"262.624354ms","start":"2026-02-25T15:13:50.230497Z","end":"2026-02-25T15:13:50.493122Z","steps":["trace[1018265330] 'process raft request' (duration: 262.334937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:50.49328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.127545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:13:50.493317Z","caller":"traceutil/trace.go:171","msg":"trace[1134255760] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5775; }","duration":"172.213117ms","start":"2026-02-25T15:13:50.321091Z","end":"2026-02-25T15:13:50.493304Z","steps":["trace[1134255760] 'agreement among raft nodes before linearized reading' (duration: 172.128575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:50.708591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.25524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:13:50.708701Z","caller":"traceutil/trace.go:171","msg":"trace[1683279756] linearizableReadLoop","detail":"{readStateIndex:5989; appliedIndex:5988; }","duration":"197.526463ms","start":"2026-02-25T15:13:50.511158Z","end":"2026-02-25T15:13:50.708685Z","steps":["trace[1683279756] 'read index received' (duration: 49.978156ms)","trace[1683279756] 'applied index is now lower than readState.Index' (duration: 147.547187ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:50.708744Z","caller":"traceutil/trace.go:171","msg":"trace[711576593] transaction","detail":"{read_only:false; response_revision:5776; number_of_response:1; }","duration":"205.333863ms","start":"2026-02-25T15:13:50.503374Z","end":"2026-02-25T15:13:50.708708Z","steps":["trace[711576593] 'process raft request' (duration: 57.867057ms)","trace[711576593] 'compare' (duration: 147.074485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:13:50.708903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.726128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5541"} {"level":"info","ts":"2026-02-25T15:13:50.708962Z","caller":"traceutil/trace.go:171","msg":"trace[495734167] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5776; }","duration":"197.821541ms","start":"2026-02-25T15:13:50.511122Z","end":"2026-02-25T15:13:50.708944Z","steps":["trace[495734167] 'agreement among raft nodes before linearized reading' (duration: 197.644026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:51.690059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.592554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:13:51.690317Z","caller":"traceutil/trace.go:171","msg":"trace[741543320] transaction","detail":"{read_only:false; response_revision:5781; number_of_response:1; }","duration":"206.337759ms","start":"2026-02-25T15:13:51.483914Z","end":"2026-02-25T15:13:51.690252Z","steps":["trace[741543320] 'process raft request' (duration: 75.468637ms)","trace[741543320] 'compare' (duration: 130.395829ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:52.241793Z","caller":"traceutil/trace.go:171","msg":"trace[344768107] transaction","detail":"{read_only:false; response_revision:5783; number_of_response:1; }","duration":"298.635305ms","start":"2026-02-25T15:13:51.943129Z","end":"2026-02-25T15:13:52.241764Z","steps":["trace[344768107] 'process raft request' (duration: 298.460059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:52.430275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.564199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T15:13:52.430424Z","caller":"traceutil/trace.go:171","msg":"trace[1071626749] transaction","detail":"{read_only:false; response_revision:5785; number_of_response:1; }","duration":"486.07044ms","start":"2026-02-25T15:13:51.94434Z","end":"2026-02-25T15:13:52.430411Z","steps":["trace[1071626749] 'process raft request' (duration: 486.008168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:52.430476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:51.944311Z","time spent":"486.142242ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T15:13:52.430473Z","caller":"traceutil/trace.go:171","msg":"trace[2087653809] transaction","detail":"{read_only:false; response_revision:5784; number_of_response:1; }","duration":"486.117381ms","start":"2026-02-25T15:13:51.944311Z","end":"2026-02-25T15:13:52.430428Z","steps":["trace[2087653809] 'process raft request' (duration: 363.334377ms)","trace[2087653809] 'compare' (duration: 122.477257ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:52.430602Z","caller":"traceutil/trace.go:171","msg":"trace[937079198] linearizableReadLoop","detail":"{readStateIndex:5998; appliedIndex:5995; }","duration":"485.798054ms","start":"2026-02-25T15:13:51.944771Z","end":"2026-02-25T15:13:52.430569Z","steps":["trace[937079198] 'read index received' (duration: 296.814628ms)","trace[937079198] 'applied index is now lower than readState.Index' (duration: 188.980986ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:52.430648Z","caller":"traceutil/trace.go:171","msg":"trace[1782085448] transaction","detail":"{read_only:false; response_revision:5786; number_of_response:1; }","duration":"314.179501ms","start":"2026-02-25T15:13:52.116443Z","end":"2026-02-25T15:13:52.430623Z","steps":["trace[1782085448] 'process raft request' (duration: 313.940695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:52.430666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:51.944292Z","time spent":"486.251444ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:13:52.430772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:52.116422Z","time spent":"314.276144ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T15:13:52.430801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.04773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80444"} {"level":"info","ts":"2026-02-25T15:13:52.430822Z","caller":"traceutil/trace.go:171","msg":"trace[582535525] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5786; }","duration":"486.070741ms","start":"2026-02-25T15:13:51.944745Z","end":"2026-02-25T15:13:52.430816Z","steps":["trace[582535525] 'agreement among raft nodes before linearized reading' (duration: 485.942197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:13:52.430838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T15:13:51.944644Z","time spent":"486.190234ms","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":80466,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-02-25T15:13:52.618777Z","caller":"traceutil/trace.go:171","msg":"trace[182006082] transaction","detail":"{read_only:false; response_revision:5787; number_of_response:1; }","duration":"295.183097ms","start":"2026-02-25T15:13:52.323563Z","end":"2026-02-25T15:13:52.618746Z","steps":["trace[182006082] 'process raft request' (duration: 266.531145ms)","trace[182006082] 'compare' (duration: 28.54237ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:55.271439Z","caller":"traceutil/trace.go:171","msg":"trace[962348180] transaction","detail":"{read_only:false; response_revision:5798; number_of_response:1; }","duration":"114.199446ms","start":"2026-02-25T15:13:55.157205Z","end":"2026-02-25T15:13:55.271405Z","steps":["trace[962348180] 'process raft request' (duration: 114.015902ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:55.394299Z","caller":"traceutil/trace.go:171","msg":"trace[888265174] transaction","detail":"{read_only:false; response_revision:5799; number_of_response:1; }","duration":"221.531957ms","start":"2026-02-25T15:13:55.172741Z","end":"2026-02-25T15:13:55.394273Z","steps":["trace[888265174] 'process raft request' (duration: 196.464586ms)","trace[888265174] 'compare' (duration: 24.874135ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:56.683222Z","caller":"traceutil/trace.go:171","msg":"trace[883557804] transaction","detail":"{read_only:false; response_revision:5806; number_of_response:1; }","duration":"141.590306ms","start":"2026-02-25T15:13:56.541593Z","end":"2026-02-25T15:13:56.683183Z","steps":["trace[883557804] 'process raft request' (duration: 106.278214ms)","trace[883557804] 'compare' (duration: 35.121327ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:57.509867Z","caller":"traceutil/trace.go:171","msg":"trace[91215710] transaction","detail":"{read_only:false; response_revision:5812; number_of_response:1; }","duration":"101.689196ms","start":"2026-02-25T15:13:57.40815Z","end":"2026-02-25T15:13:57.509839Z","steps":["trace[91215710] 'process raft request' (duration: 101.487301ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:57.6599Z","caller":"traceutil/trace.go:171","msg":"trace[44281346] transaction","detail":"{read_only:false; response_revision:5813; number_of_response:1; }","duration":"104.95331ms","start":"2026-02-25T15:13:57.55492Z","end":"2026-02-25T15:13:57.659873Z","steps":["trace[44281346] 'process raft request' (duration: 60.796933ms)","trace[44281346] 'compare' (duration: 43.794657ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:13:58.707117Z","caller":"traceutil/trace.go:171","msg":"trace[1851587169] transaction","detail":"{read_only:false; response_revision:5817; number_of_response:1; }","duration":"101.48759ms","start":"2026-02-25T15:13:58.605606Z","end":"2026-02-25T15:13:58.707094Z","steps":["trace[1851587169] 'process raft request' (duration: 101.184853ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:13:58.861083Z","caller":"traceutil/trace.go:171","msg":"trace[807356834] transaction","detail":"{read_only:false; response_revision:5819; number_of_response:1; }","duration":"131.673422ms","start":"2026-02-25T15:13:58.729382Z","end":"2026-02-25T15:13:58.861056Z","steps":["trace[807356834] 'process raft request' (duration: 131.512038ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:14:13.328048Z","caller":"traceutil/trace.go:171","msg":"trace[1001039230] linearizableReadLoop","detail":"{readStateIndex:6121; appliedIndex:6120; }","duration":"177.506713ms","start":"2026-02-25T15:14:13.150513Z","end":"2026-02-25T15:14:13.32802Z","steps":["trace[1001039230] 'read index received' (duration: 155.574992ms)","trace[1001039230] 'applied index is now lower than readState.Index' (duration: 21.930171ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:14:13.328067Z","caller":"traceutil/trace.go:171","msg":"trace[1218154252] transaction","detail":"{read_only:false; response_revision:5903; number_of_response:1; }","duration":"213.624383ms","start":"2026-02-25T15:14:13.11442Z","end":"2026-02-25T15:14:13.328044Z","steps":["trace[1218154252] 'process raft request' (duration: 191.664662ms)","trace[1218154252] 'compare' (duration: 21.750705ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:14:13.328319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.788039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/loki-gateway-6b458d8465-km789.189786009e7d8c6b\" ","response":"range_response_count:1 size:797"} {"level":"info","ts":"2026-02-25T15:14:13.328397Z","caller":"traceutil/trace.go:171","msg":"trace[1417906534] range","detail":"{range_begin:/registry/events/monitoring/loki-gateway-6b458d8465-km789.189786009e7d8c6b; range_end:; response_count:1; response_revision:5903; }","duration":"177.876352ms","start":"2026-02-25T15:14:13.150501Z","end":"2026-02-25T15:14:13.328377Z","steps":["trace[1417906534] 'agreement among raft nodes before linearized reading' (duration: 177.651406ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:14:17.904689Z","caller":"traceutil/trace.go:171","msg":"trace[2141942633] transaction","detail":"{read_only:false; response_revision:5928; number_of_response:1; }","duration":"141.968285ms","start":"2026-02-25T15:14:17.762698Z","end":"2026-02-25T15:14:17.904667Z","steps":["trace[2141942633] 'process raft request' (duration: 141.820171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:14:20.311268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.86425ms","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-25T15:14:20.311371Z","caller":"traceutil/trace.go:171","msg":"trace[109215223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5937; }","duration":"223.007104ms","start":"2026-02-25T15:14:20.088342Z","end":"2026-02-25T15:14:20.311349Z","steps":["trace[109215223] 'range keys from in-memory index tree' (duration: 222.708106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:14:20.311461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.760301ms","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-25T15:14:20.311605Z","caller":"traceutil/trace.go:171","msg":"trace[1611853548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5937; }","duration":"219.952156ms","start":"2026-02-25T15:14:20.091631Z","end":"2026-02-25T15:14:20.311583Z","steps":["trace[1611853548] 'range keys from in-memory index tree' (duration: 219.585557ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:14:40.216578Z","caller":"traceutil/trace.go:171","msg":"trace[840774665] transaction","detail":"{read_only:false; response_revision:6030; number_of_response:1; }","duration":"163.356241ms","start":"2026-02-25T15:14:40.053203Z","end":"2026-02-25T15:14:40.216559Z","steps":["trace[840774665] 'process raft request' (duration: 163.205177ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:18:30.839506Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5640} {"level":"info","ts":"2026-02-25T15:18:30.922031Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5640,"took":"80.511868ms","hash":1033059182,"current-db-size-bytes":40116224,"current-db-size":"40 MB","current-db-size-in-use-bytes":15093760,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-02-25T15:18:30.922104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1033059182,"revision":5640,"compact-revision":2765} {"level":"warn","ts":"2026-02-25T15:18:38.397761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.341174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:18:38.397919Z","caller":"traceutil/trace.go:171","msg":"trace[537320358] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:7139; }","duration":"151.517619ms","start":"2026-02-25T15:18:38.246329Z","end":"2026-02-25T15:18:38.397847Z","steps":["trace[537320358] 'count revisions from in-memory index tree' (duration: 151.253763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:18:38.398045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.91942ms","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-25T15:18:38.398158Z","caller":"traceutil/trace.go:171","msg":"trace[87025656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7139; }","duration":"206.123764ms","start":"2026-02-25T15:18:38.192009Z","end":"2026-02-25T15:18:38.398133Z","steps":["trace[87025656] 'range keys from in-memory index tree' (duration: 205.632732ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:20:08.355723Z","caller":"traceutil/trace.go:171","msg":"trace[1059749252] transaction","detail":"{read_only:false; response_revision:7549; number_of_response:1; }","duration":"119.635953ms","start":"2026-02-25T15:20:08.236054Z","end":"2026-02-25T15:20:08.35569Z","steps":["trace[1059749252] 'process raft request' (duration: 115.020493ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:20:08.382872Z","caller":"traceutil/trace.go:171","msg":"trace[2074763809] transaction","detail":"{read_only:false; response_revision:7550; number_of_response:1; }","duration":"145.210806ms","start":"2026-02-25T15:20:08.237626Z","end":"2026-02-25T15:20:08.382836Z","steps":["trace[2074763809] 'process raft request' (duration: 144.99908ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:23:24.038358Z","caller":"traceutil/trace.go:171","msg":"trace[815401863] linearizableReadLoop","detail":"{readStateIndex:8782; appliedIndex:8781; }","duration":"123.823124ms","start":"2026-02-25T15:23:23.914499Z","end":"2026-02-25T15:23:24.038322Z","steps":["trace[815401863] 'read index received' (duration: 81.438483ms)","trace[815401863] 'applied index is now lower than readState.Index' (duration: 42.383041ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T15:23:24.038439Z","caller":"traceutil/trace.go:171","msg":"trace[1981027941] transaction","detail":"{read_only:false; response_revision:8450; number_of_response:1; }","duration":"132.029076ms","start":"2026-02-25T15:23:23.906367Z","end":"2026-02-25T15:23:24.038396Z","steps":["trace[1981027941] 'process raft request' (duration: 89.673227ms)","trace[1981027941] 'compare' (duration: 42.196925ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T15:23:24.03855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.032538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T15:23:24.038633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.070408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-02-25T15:23:24.03864Z","caller":"traceutil/trace.go:171","msg":"trace[958444822] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:8450; }","duration":"124.166362ms","start":"2026-02-25T15:23:23.914456Z","end":"2026-02-25T15:23:24.038622Z","steps":["trace[958444822] 'agreement among raft nodes before linearized reading' (duration: 124.015008ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T15:23:24.038682Z","caller":"traceutil/trace.go:171","msg":"trace[1621102402] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8450; }","duration":"112.173141ms","start":"2026-02-25T15:23:23.926495Z","end":"2026-02-25T15:23:24.038668Z","steps":["trace[1621102402] 'agreement among raft nodes before linearized reading' (duration: 112.002937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:23:24.038722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.075743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/auth-system/keycloak-0\" ","response":"range_response_count:1 size:7895"} {"level":"info","ts":"2026-02-25T15:23:24.038794Z","caller":"traceutil/trace.go:171","msg":"trace[2087487570] range","detail":"{range_begin:/registry/pods/auth-system/keycloak-0; range_end:; response_count:1; response_revision:8450; }","duration":"106.183125ms","start":"2026-02-25T15:23:23.932594Z","end":"2026-02-25T15:23:24.038777Z","steps":["trace[2087487570] 'agreement among raft nodes before linearized reading' (duration: 105.960799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:23:24.48125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.643924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-public/\" range_end:\"/registry/pods/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T15:23:24.481365Z","caller":"traceutil/trace.go:171","msg":"trace[654455942] range","detail":"{range_begin:/registry/pods/kube-public/; range_end:/registry/pods/kube-public0; response_count:0; response_revision:8451; }","duration":"154.792088ms","start":"2026-02-25T15:23:24.326547Z","end":"2026-02-25T15:23:24.481339Z","steps":["trace[654455942] 'range keys from in-memory index tree' (duration: 154.56443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T15:23:24.481392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.496898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/auth-system/keycloak-0\" ","response":"range_response_count:1 size:7895"} {"level":"info","ts":"2026-02-25T15:23:24.48151Z","caller":"traceutil/trace.go:171","msg":"trace[2063207300] range","detail":"{range_begin:/registry/pods/auth-system/keycloak-0; range_end:; response_count:1; response_revision:8451; }","duration":"152.640451ms","start":"2026-02-25T15:23:24.328826Z","end":"2026-02-25T15:23:24.481466Z","steps":["trace[2063207300] 'range keys from in-memory index tree' (duration: 152.228341ms)"],"step_count":1}