{"level":"warn","ts":"2026-09-08T15:48:01.363227Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:48:01.363343Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.57: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://162.253.55.57:2380","--initial-cluster=instance=https://162.253.55.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.57: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-09-08T15:48:01.36342Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:48:01.363439Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.57:2380"]} {"level":"info","ts":"2026-09-08T15:48:01.363482Z","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-09-08T15:48:01.364152Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.57:2379"]} {"level":"info","ts":"2026-09-08T15:48:01.364237Z","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://162.253.55.57:2380"],"listen-peer-urls":["https://162.253.55.57:2380"],"advertise-client-urls":["https://162.253.55.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.57: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-09-08T15:48:01.371576Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.021308ms"} {"level":"info","ts":"2026-09-08T15:48:01.377141Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db28a37eabf8270","cluster-id":"33328f64ab44b15e"} {"level":"info","ts":"2026-09-08T15:48:01.377245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T15:48:01.377271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became follower at term 0"} {"level":"info","ts":"2026-09-08T15:48:01.377281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db28a37eabf8270 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T15:48:01.377291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became follower at term 1"} {"level":"info","ts":"2026-09-08T15:48:01.377335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 switched to configuration voters=(987003241113813616)"} {"level":"warn","ts":"2026-09-08T15:48:01.393924Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T15:48:01.396755Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T15:48:01.398766Z","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-09-08T15:48:01.401622Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db28a37eabf8270","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T15:48:01.401868Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db28a37eabf8270","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T15:48:01.402208Z","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-09-08T15:48:01.402318Z","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-09-08T15:48:01.402363Z","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-09-08T15:48:01.40487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 switched to configuration voters=(987003241113813616)"} {"level":"info","ts":"2026-09-08T15:48:01.405042Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"33328f64ab44b15e","local-member-id":"db28a37eabf8270","added-peer-id":"db28a37eabf8270","added-peer-peer-urls":["https://162.253.55.57:2380"]} {"level":"info","ts":"2026-09-08T15:48:01.406481Z","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-09-08T15:48:01.406664Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.57:2380"} {"level":"info","ts":"2026-09-08T15:48:01.406726Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db28a37eabf8270","initial-advertise-peer-urls":["https://162.253.55.57:2380"],"listen-peer-urls":["https://162.253.55.57:2380"],"advertise-client-urls":["https://162.253.55.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T15:48:01.406728Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.57:2380"} {"level":"info","ts":"2026-09-08T15:48:01.406785Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T15:48:02.277531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T15:48:02.277625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T15:48:02.277649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 received MsgPreVoteResp from db28a37eabf8270 at term 1"} {"level":"info","ts":"2026-09-08T15:48:02.277669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became candidate at term 2"} {"level":"info","ts":"2026-09-08T15:48:02.277679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 received MsgVoteResp from db28a37eabf8270 at term 2"} {"level":"info","ts":"2026-09-08T15:48:02.277695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became leader at term 2"} {"level":"info","ts":"2026-09-08T15:48:02.277708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db28a37eabf8270 elected leader db28a37eabf8270 at term 2"} {"level":"info","ts":"2026-09-08T15:48:02.278919Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:02.28003Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db28a37eabf8270","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.57:2379]}","request-path":"/0/members/db28a37eabf8270/attributes","cluster-id":"33328f64ab44b15e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T15:48:02.280162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:48:02.280192Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:48:02.280422Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T15:48:02.280461Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T15:48:02.281221Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"33328f64ab44b15e","local-member-id":"db28a37eabf8270","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:02.281398Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:02.281453Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:02.283602Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.57:2379"} {"level":"info","ts":"2026-09-08T15:48:02.284874Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-08T15:48:26.839661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.994412ms","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-09-08T15:48:26.839787Z","caller":"traceutil/trace.go:171","msg":"trace[1048113075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"193.166097ms","start":"2026-09-08T15:48:26.646599Z","end":"2026-09-08T15:48:26.839765Z","steps":["trace[1048113075] 'range keys from in-memory index tree' (duration: 192.91283ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:48.885284Z","caller":"traceutil/trace.go:171","msg":"trace[105376345] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"118.155251ms","start":"2026-09-08T15:48:48.767101Z","end":"2026-09-08T15:48:48.885256Z","steps":["trace[105376345] 'process raft request' (duration: 117.954826ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:48.941345Z","caller":"traceutil/trace.go:171","msg":"trace[1897159215] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"151.351338ms","start":"2026-09-08T15:48:48.789962Z","end":"2026-09-08T15:48:48.941314Z","steps":["trace[1897159215] 'process raft request' (duration: 150.746241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:49.3548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.773109ms","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-09-08T15:48:49.35493Z","caller":"traceutil/trace.go:171","msg":"trace[1617811702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"373.954515ms","start":"2026-09-08T15:48:48.980949Z","end":"2026-09-08T15:48:49.354904Z","steps":["trace[1617811702] 'range keys from in-memory index tree' (duration: 373.542182ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:49.354983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:48.980926Z","time spent":"374.045196ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-08T15:48:49.355251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.404226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:48:49.355347Z","caller":"traceutil/trace.go:171","msg":"trace[1142080699] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"294.248676ms","start":"2026-09-08T15:48:49.061081Z","end":"2026-09-08T15:48:49.355329Z","steps":["trace[1142080699] 'read index received' (duration: 35.168892ms)","trace[1142080699] 'applied index is now lower than readState.Index' (duration: 259.078514ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:49.35537Z","caller":"traceutil/trace.go:171","msg":"trace[1317510492] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"351.251464ms","start":"2026-09-08T15:48:49.004088Z","end":"2026-09-08T15:48:49.35534Z","steps":["trace[1317510492] 'process raft request' (duration: 92.251201ms)","trace[1317510492] 'compare' (duration: 258.289903ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:48:49.355492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:49.004066Z","time spent":"351.359896ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-08T15:48:49.355522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.449792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fbx4m\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-08T15:48:49.355553Z","caller":"traceutil/trace.go:171","msg":"trace[516597944] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fbx4m; range_end:; response_count:1; response_revision:596; }","duration":"294.520304ms","start":"2026-09-08T15:48:49.061023Z","end":"2026-09-08T15:48:49.355544Z","steps":["trace[516597944] 'agreement among raft nodes before linearized reading' (duration: 294.36778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:50.044357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.744134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:48:50.04446Z","caller":"traceutil/trace.go:171","msg":"trace[835065061] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"359.398343ms","start":"2026-09-08T15:48:49.685045Z","end":"2026-09-08T15:48:50.044444Z","steps":["trace[835065061] 'read index received' (duration: 66.296329ms)","trace[835065061] 'applied index is now lower than readState.Index' (duration: 293.100844ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:50.044604Z","caller":"traceutil/trace.go:171","msg":"trace[705879668] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"670.589267ms","start":"2026-09-08T15:48:49.373992Z","end":"2026-09-08T15:48:50.044581Z","steps":["trace[705879668] 'process raft request' (duration: 377.366749ms)","trace[705879668] 'compare' (duration: 292.500308ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:48:50.044696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:49.373971Z","time spent":"670.67926ms","remote":"127.0.0.1:40336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:48:50.044814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.750873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:48:50.044892Z","caller":"traceutil/trace.go:171","msg":"trace[2009434035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"359.865146ms","start":"2026-09-08T15:48:49.685005Z","end":"2026-09-08T15:48:50.04487Z","steps":["trace[2009434035] 'agreement among raft nodes before linearized reading' (duration: 359.743233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:50.044921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:49.684986Z","time spent":"359.928758ms","remote":"127.0.0.1:40158","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-09-08T15:48:50.057495Z","caller":"traceutil/trace.go:171","msg":"trace[541040058] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"277.568646ms","start":"2026-09-08T15:48:49.779869Z","end":"2026-09-08T15:48:50.057437Z","steps":["trace[541040058] 'process raft request' (duration: 276.912367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:50.05752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.88069ms","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-09-08T15:48:50.058266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.934663ms","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-09-08T15:48:50.058353Z","caller":"traceutil/trace.go:171","msg":"trace[1765249228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"341.049796ms","start":"2026-09-08T15:48:49.717271Z","end":"2026-09-08T15:48:50.058321Z","steps":["trace[1765249228] 'agreement among raft nodes before linearized reading' (duration: 340.222073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:50.058393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:49.717259Z","time spent":"341.127608ms","remote":"127.0.0.1:40442","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-09-08T15:48:50.058406Z","caller":"traceutil/trace.go:171","msg":"trace[2069074051] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"314.772705ms","start":"2026-09-08T15:48:49.74359Z","end":"2026-09-08T15:48:50.058362Z","steps":["trace[2069074051] 'agreement among raft nodes before linearized reading' (duration: 313.794737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:50.058478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:49.743576Z","time spent":"314.877528ms","remote":"127.0.0.1:40442","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-09-08T15:49:03.824459Z","caller":"traceutil/trace.go:171","msg":"trace[1092038346] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"112.458ms","start":"2026-09-08T15:49:03.711965Z","end":"2026-09-08T15:49:03.824423Z","steps":["trace[1092038346] 'process raft request' (duration: 112.242494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:06.481593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.739626ms","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-09-08T15:49:06.481706Z","caller":"traceutil/trace.go:171","msg":"trace[282122495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"319.90685ms","start":"2026-09-08T15:49:06.16178Z","end":"2026-09-08T15:49:06.481687Z","steps":["trace[282122495] 'range keys from in-memory index tree' (duration: 319.57307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:06.481746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:06.161769Z","time spent":"319.967443ms","remote":"127.0.0.1:40442","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-09-08T15:49:06.481742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.182819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8sc5q\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-09-08T15:49:06.481961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.935391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-08T15:49:06.482003Z","caller":"traceutil/trace.go:171","msg":"trace[1164499168] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8sc5q; range_end:; response_count:1; response_revision:763; }","duration":"302.458406ms","start":"2026-09-08T15:49:06.179481Z","end":"2026-09-08T15:49:06.481939Z","steps":["trace[1164499168] 'range keys from in-memory index tree' (duration: 301.971572ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:06.482057Z","caller":"traceutil/trace.go:171","msg":"trace[1588622106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"326.063665ms","start":"2026-09-08T15:49:06.155967Z","end":"2026-09-08T15:49:06.482031Z","steps":["trace[1588622106] 'range keys from in-memory index tree' (duration: 325.775177ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:06.482128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:06.179461Z","time spent":"302.591049ms","remote":"127.0.0.1:40336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8sc5q\" "} {"level":"warn","ts":"2026-09-08T15:49:06.482136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:06.155951Z","time spent":"326.146227ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-08T15:49:30.836286Z","caller":"traceutil/trace.go:171","msg":"trace[1298615961] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"125.174234ms","start":"2026-09-08T15:49:30.711072Z","end":"2026-09-08T15:49:30.836246Z","steps":["trace[1298615961] 'process raft request' (duration: 95.485604ms)","trace[1298615961] 'compare' (duration: 29.34482ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:30.836226Z","caller":"traceutil/trace.go:171","msg":"trace[1599338326] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"123.972241ms","start":"2026-09-08T15:49:30.712224Z","end":"2026-09-08T15:49:30.836196Z","steps":["trace[1599338326] 'process raft request' (duration: 123.896868ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:33.38284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.893057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:33.38293Z","caller":"traceutil/trace.go:171","msg":"trace[654633924] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1127; }","duration":"106.025292ms","start":"2026-09-08T15:49:33.276883Z","end":"2026-09-08T15:49:33.382909Z","steps":["trace[654633924] 'count revisions from in-memory index tree' (duration: 105.831766ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:33.383202Z","caller":"traceutil/trace.go:171","msg":"trace[616740945] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:1127; }","duration":"100.031992ms","start":"2026-09-08T15:49:33.283146Z","end":"2026-09-08T15:49:33.383178Z","steps":["trace[616740945] 'range keys from in-memory index tree' (duration: 99.902439ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:33.700442Z","caller":"traceutil/trace.go:171","msg":"trace[1964862023] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"143.086632ms","start":"2026-09-08T15:49:33.557298Z","end":"2026-09-08T15:49:33.700385Z","steps":["trace[1964862023] 'process raft request' (duration: 102.536023ms)","trace[1964862023] 'compare' (duration: 40.354194ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:34.876052Z","caller":"traceutil/trace.go:171","msg":"trace[1773277731] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"119.563936ms","start":"2026-09-08T15:49:34.756465Z","end":"2026-09-08T15:49:34.876029Z","steps":["trace[1773277731] 'process raft request' (duration: 119.398251ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:36.166815Z","caller":"traceutil/trace.go:171","msg":"trace[1923605684] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"140.250321ms","start":"2026-09-08T15:49:36.026523Z","end":"2026-09-08T15:49:36.166774Z","steps":["trace[1923605684] 'process raft request' (duration: 139.808169ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:36.330326Z","caller":"traceutil/trace.go:171","msg":"trace[1318767623] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"158.407195ms","start":"2026-09-08T15:49:36.17187Z","end":"2026-09-08T15:49:36.330277Z","steps":["trace[1318767623] 'process raft request' (duration: 158.000964ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:36.330389Z","caller":"traceutil/trace.go:171","msg":"trace[731643158] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"154.750463ms","start":"2026-09-08T15:49:36.17562Z","end":"2026-09-08T15:49:36.330371Z","steps":["trace[731643158] 'process raft request' (duration: 154.67273ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:36.330741Z","caller":"traceutil/trace.go:171","msg":"trace[2045946569] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"156.650677ms","start":"2026-09-08T15:49:36.174073Z","end":"2026-09-08T15:49:36.330723Z","steps":["trace[2045946569] 'process raft request' (duration: 156.140142ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:36.330722Z","caller":"traceutil/trace.go:171","msg":"trace[962959123] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"156.719358ms","start":"2026-09-08T15:49:36.173956Z","end":"2026-09-08T15:49:36.330675Z","steps":["trace[962959123] 'process raft request' (duration: 156.175032ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:37.0552Z","caller":"traceutil/trace.go:171","msg":"trace[1619359593] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"168.846012ms","start":"2026-09-08T15:49:36.886328Z","end":"2026-09-08T15:49:37.055174Z","steps":["trace[1619359593] 'process raft request' (duration: 168.620995ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:37.25176Z","caller":"traceutil/trace.go:171","msg":"trace[1535008540] linearizableReadLoop","detail":"{readStateIndex:1190; appliedIndex:1188; }","duration":"302.342662ms","start":"2026-09-08T15:49:36.949389Z","end":"2026-09-08T15:49:37.251732Z","steps":["trace[1535008540] 'read index received' (duration: 105.58043ms)","trace[1535008540] 'applied index is now lower than readState.Index' (duration: 196.760462ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:37.252209Z","caller":"traceutil/trace.go:171","msg":"trace[1363664191] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"344.215819ms","start":"2026-09-08T15:49:36.907978Z","end":"2026-09-08T15:49:37.252194Z","steps":["trace[1363664191] 'process raft request' (duration: 330.650075ms)","trace[1363664191] 'compare' (duration: 12.821782ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:37.252331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:36.907951Z","time spent":"344.29827ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:49:37.252399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.989602ms","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-09-08T15:49:37.252482Z","caller":"traceutil/trace.go:171","msg":"trace[1083320027] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1149; }","duration":"303.114325ms","start":"2026-09-08T15:49:36.949346Z","end":"2026-09-08T15:49:37.25246Z","steps":["trace[1083320027] 'agreement among raft nodes before linearized reading' (duration: 302.888329ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:37.252574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:36.949324Z","time spent":"303.237708ms","remote":"127.0.0.1:40442","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":"warn","ts":"2026-09-08T15:49:37.252859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.62675ms","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-09-08T15:49:37.252966Z","caller":"traceutil/trace.go:171","msg":"trace[380297345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1149; }","duration":"177.782515ms","start":"2026-09-08T15:49:37.07516Z","end":"2026-09-08T15:49:37.252943Z","steps":["trace[380297345] 'agreement among raft nodes before linearized reading' (duration: 176.924381ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:38.470016Z","caller":"traceutil/trace.go:171","msg":"trace[181754820] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"128.778597ms","start":"2026-09-08T15:49:38.341196Z","end":"2026-09-08T15:49:38.469974Z","steps":["trace[181754820] 'process raft request' (duration: 128.383336ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:39.521019Z","caller":"traceutil/trace.go:171","msg":"trace[599107235] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"202.528715ms","start":"2026-09-08T15:49:39.318445Z","end":"2026-09-08T15:49:39.520974Z","steps":["trace[599107235] 'process raft request' (duration: 127.054049ms)","trace[599107235] 'compare' (duration: 75.270751ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:39.591224Z","caller":"traceutil/trace.go:171","msg":"trace[969609217] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1198; }","duration":"219.886238ms","start":"2026-09-08T15:49:39.37131Z","end":"2026-09-08T15:49:39.591196Z","steps":["trace[969609217] 'read index received' (duration: 74.234323ms)","trace[969609217] 'applied index is now lower than readState.Index' (duration: 145.650655ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:39.591293Z","caller":"traceutil/trace.go:171","msg":"trace[1937109671] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"272.720774ms","start":"2026-09-08T15:49:39.318546Z","end":"2026-09-08T15:49:39.591267Z","steps":["trace[1937109671] 'process raft request' (duration: 272.479596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:39.59148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.161525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io\" ","response":"range_response_count:1 size:21237"} {"level":"info","ts":"2026-09-08T15:49:39.59154Z","caller":"traceutil/trace.go:171","msg":"trace[1085856545] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io; range_end:; response_count:1; response_revision:1159; }","duration":"220.288959ms","start":"2026-09-08T15:49:39.371233Z","end":"2026-09-08T15:49:39.591522Z","steps":["trace[1085856545] 'agreement among raft nodes before linearized reading' (duration: 220.070233ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:40.19154Z","caller":"traceutil/trace.go:171","msg":"trace[1850983575] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1204; }","duration":"136.441004ms","start":"2026-09-08T15:49:40.05507Z","end":"2026-09-08T15:49:40.191511Z","steps":["trace[1850983575] 'read index received' (duration: 79.922854ms)","trace[1850983575] 'applied index is now lower than readState.Index' (duration: 56.51685ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:40.191682Z","caller":"traceutil/trace.go:171","msg":"trace[1596535914] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"138.060539ms","start":"2026-09-08T15:49:40.053584Z","end":"2026-09-08T15:49:40.191645Z","steps":["trace[1596535914] 'process raft request' (duration: 137.838023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:40.191798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.709731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2897"} {"level":"info","ts":"2026-09-08T15:49:40.191848Z","caller":"traceutil/trace.go:171","msg":"trace[955106690] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1165; }","duration":"136.849125ms","start":"2026-09-08T15:49:40.054985Z","end":"2026-09-08T15:49:40.191834Z","steps":["trace[955106690] 'agreement among raft nodes before linearized reading' (duration: 136.6445ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:40.191836Z","caller":"traceutil/trace.go:171","msg":"trace[62589069] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"142.103644ms","start":"2026-09-08T15:49:40.04966Z","end":"2026-09-08T15:49:40.191764Z","steps":["trace[62589069] 'process raft request' (duration: 85.222494ms)","trace[62589069] 'compare' (duration: 56.363116ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:40.398617Z","caller":"traceutil/trace.go:171","msg":"trace[1104593370] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1208; }","duration":"134.056467ms","start":"2026-09-08T15:49:40.26453Z","end":"2026-09-08T15:49:40.398587Z","steps":["trace[1104593370] 'read index received' (duration: 107.69182ms)","trace[1104593370] 'applied index is now lower than readState.Index' (duration: 26.363387ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:40.398733Z","caller":"traceutil/trace.go:171","msg":"trace[1447529855] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"138.657667ms","start":"2026-09-08T15:49:40.260025Z","end":"2026-09-08T15:49:40.398683Z","steps":["trace[1447529855] 'process raft request' (duration: 112.27523ms)","trace[1447529855] 'compare' (duration: 26.162111ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:40.398872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.315294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/cert-manager-webhook\" ","response":"range_response_count:1 size:2558"} {"level":"info","ts":"2026-09-08T15:49:40.398925Z","caller":"traceutil/trace.go:171","msg":"trace[1257843811] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/cert-manager-webhook; range_end:; response_count:1; response_revision:1168; }","duration":"134.451008ms","start":"2026-09-08T15:49:40.264456Z","end":"2026-09-08T15:49:40.398907Z","steps":["trace[1257843811] 'agreement among raft nodes before linearized reading' (duration: 134.271463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:40.399039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.957644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/cert-manager-webhook\" ","response":"range_response_count:1 size:2611"} {"level":"info","ts":"2026-09-08T15:49:40.399176Z","caller":"traceutil/trace.go:171","msg":"trace[248676270] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/cert-manager-webhook; range_end:; response_count:1; response_revision:1168; }","duration":"134.233292ms","start":"2026-09-08T15:49:40.264919Z","end":"2026-09-08T15:49:40.399152Z","steps":["trace[248676270] 'agreement among raft nodes before linearized reading' (duration: 133.846751ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:40.553497Z","caller":"traceutil/trace.go:171","msg":"trace[1781666470] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"135.965161ms","start":"2026-09-08T15:49:40.417498Z","end":"2026-09-08T15:49:40.553463Z","steps":["trace[1781666470] 'process raft request' (duration: 82.093795ms)","trace[1781666470] 'compare' (duration: 53.647239ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:40.804213Z","caller":"traceutil/trace.go:171","msg":"trace[2120297312] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"243.255429ms","start":"2026-09-08T15:49:40.560875Z","end":"2026-09-08T15:49:40.804131Z","steps":["trace[2120297312] 'process raft request' (duration: 242.945379ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:40.804251Z","caller":"traceutil/trace.go:171","msg":"trace[909673484] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"242.242501ms","start":"2026-09-08T15:49:40.56198Z","end":"2026-09-08T15:49:40.804222Z","steps":["trace[909673484] 'process raft request' (duration: 242.043164ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:40.804219Z","caller":"traceutil/trace.go:171","msg":"trace[383128257] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"201.148886ms","start":"2026-09-08T15:49:40.603036Z","end":"2026-09-08T15:49:40.804184Z","steps":["trace[383128257] 'process raft request' (duration: 201.042822ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:40.816698Z","caller":"traceutil/trace.go:171","msg":"trace[631994527] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1211; }","duration":"130.841226ms","start":"2026-09-08T15:49:40.685833Z","end":"2026-09-08T15:49:40.816674Z","steps":["trace[631994527] 'read index received' (duration: 118.109525ms)","trace[631994527] 'applied index is now lower than readState.Index' (duration: 12.730081ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:40.816863Z","caller":"traceutil/trace.go:171","msg":"trace[2087460161] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1173; }","duration":"205.15083ms","start":"2026-09-08T15:49:40.611661Z","end":"2026-09-08T15:49:40.816812Z","steps":["trace[2087460161] 'process raft request' (duration: 204.917833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:40.816941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.041651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:40.816987Z","caller":"traceutil/trace.go:171","msg":"trace[1746744591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1173; }","duration":"131.183215ms","start":"2026-09-08T15:49:40.685792Z","end":"2026-09-08T15:49:40.816975Z","steps":["trace[1746744591] 'agreement among raft nodes before linearized reading' (duration: 130.970969ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:40.950907Z","caller":"traceutil/trace.go:171","msg":"trace[469674397] linearizableReadLoop","detail":"{readStateIndex:1216; appliedIndex:1215; }","duration":"132.253795ms","start":"2026-09-08T15:49:40.818611Z","end":"2026-09-08T15:49:40.950864Z","steps":["trace[469674397] 'read index received' (duration: 90.701548ms)","trace[469674397] 'applied index is now lower than readState.Index' (duration: 41.550327ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:40.951068Z","caller":"traceutil/trace.go:171","msg":"trace[487716872] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"139.308055ms","start":"2026-09-08T15:49:40.811716Z","end":"2026-09-08T15:49:40.951024Z","steps":["trace[487716872] 'process raft request' (duration: 97.509462ms)","trace[487716872] 'compare' (duration: 41.427133ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:40.951204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.548593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:40.951274Z","caller":"traceutil/trace.go:171","msg":"trace[47817415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1174; }","duration":"132.693987ms","start":"2026-09-08T15:49:40.818564Z","end":"2026-09-08T15:49:40.951258Z","steps":["trace[47817415] 'agreement among raft nodes before linearized reading' (duration: 132.522102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:40.951579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.923339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2929"} {"level":"info","ts":"2026-09-08T15:49:40.951747Z","caller":"traceutil/trace.go:171","msg":"trace[314922532] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1174; }","duration":"131.164525ms","start":"2026-09-08T15:49:40.820554Z","end":"2026-09-08T15:49:40.951719Z","steps":["trace[314922532] 'agreement among raft nodes before linearized reading' (duration: 130.782704ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:41.81589Z","caller":"traceutil/trace.go:171","msg":"trace[479561970] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"214.663569ms","start":"2026-09-08T15:49:41.601191Z","end":"2026-09-08T15:49:41.815854Z","steps":["trace[479561970] 'process raft request' (duration: 214.433143ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:42.927277Z","caller":"traceutil/trace.go:171","msg":"trace[1667917330] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"113.587316ms","start":"2026-09-08T15:49:42.813658Z","end":"2026-09-08T15:49:42.927246Z","steps":["trace[1667917330] 'process raft request' (duration: 113.353379ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:43.410918Z","caller":"traceutil/trace.go:171","msg":"trace[1987304296] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"207.440254ms","start":"2026-09-08T15:49:43.203451Z","end":"2026-09-08T15:49:43.410891Z","steps":["trace[1987304296] 'process raft request' (duration: 207.29699ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:43.582039Z","caller":"traceutil/trace.go:171","msg":"trace[1535169986] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"160.800784ms","start":"2026-09-08T15:49:43.421201Z","end":"2026-09-08T15:49:43.582002Z","steps":["trace[1535169986] 'read index received' (duration: 115.932013ms)","trace[1535169986] 'applied index is now lower than readState.Index' (duration: 44.867031ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:43.582245Z","caller":"traceutil/trace.go:171","msg":"trace[474361731] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"213.237318ms","start":"2026-09-08T15:49:43.368955Z","end":"2026-09-08T15:49:43.582192Z","steps":["trace[474361731] 'process raft request' (duration: 168.248604ms)","trace[474361731] 'compare' (duration: 44.627094ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:43.582302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.078841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:49:43.58236Z","caller":"traceutil/trace.go:171","msg":"trace[1538393468] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1184; }","duration":"161.289218ms","start":"2026-09-08T15:49:43.421059Z","end":"2026-09-08T15:49:43.582348Z","steps":["trace[1538393468] 'agreement among raft nodes before linearized reading' (duration: 161.152074ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:44.210376Z","caller":"traceutil/trace.go:171","msg":"trace[707973859] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"185.221725ms","start":"2026-09-08T15:49:44.025126Z","end":"2026-09-08T15:49:44.210348Z","steps":["trace[707973859] 'process raft request' (duration: 185.02359ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:45.821029Z","caller":"traceutil/trace.go:171","msg":"trace[341520202] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"136.085404ms","start":"2026-09-08T15:49:45.684915Z","end":"2026-09-08T15:49:45.821001Z","steps":["trace[341520202] 'read index received' (duration: 96.619216ms)","trace[341520202] 'applied index is now lower than readState.Index' (duration: 39.465138ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:45.821425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.488775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:45.82142Z","caller":"traceutil/trace.go:171","msg":"trace[324032188] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"181.802037ms","start":"2026-09-08T15:49:45.639576Z","end":"2026-09-08T15:49:45.821378Z","steps":["trace[324032188] 'process raft request' (duration: 142.081722ms)","trace[324032188] 'compare' (duration: 39.196161ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:45.821472Z","caller":"traceutil/trace.go:171","msg":"trace[1255778063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"136.584569ms","start":"2026-09-08T15:49:45.684876Z","end":"2026-09-08T15:49:45.82146Z","steps":["trace[1255778063] 'agreement among raft nodes before linearized reading' (duration: 136.498435ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:45.984043Z","caller":"traceutil/trace.go:171","msg":"trace[334266196] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"107.34516ms","start":"2026-09-08T15:49:45.876646Z","end":"2026-09-08T15:49:45.983992Z","steps":["trace[334266196] 'process raft request' (duration: 43.933214ms)","trace[334266196] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/masterleases/162.253.55.57; req_size:116; } (duration: 63.099616ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:47.224961Z","caller":"traceutil/trace.go:171","msg":"trace[1534681311] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"109.0858ms","start":"2026-09-08T15:49:47.115843Z","end":"2026-09-08T15:49:47.224929Z","steps":["trace[1534681311] 'process raft request' (duration: 108.308897ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:48.70473Z","caller":"traceutil/trace.go:171","msg":"trace[591631067] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"113.32583ms","start":"2026-09-08T15:49:48.591372Z","end":"2026-09-08T15:49:48.704698Z","steps":["trace[591631067] 'process raft request' (duration: 113.006611ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:49.821304Z","caller":"traceutil/trace.go:171","msg":"trace[976681960] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"107.376231ms","start":"2026-09-08T15:49:49.713886Z","end":"2026-09-08T15:49:49.821263Z","steps":["trace[976681960] 'process raft request' (duration: 45.349095ms)","trace[976681960] 'compare' (duration: 61.913933ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:49.992559Z","caller":"traceutil/trace.go:171","msg":"trace[342058263] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"112.882076ms","start":"2026-09-08T15:49:49.879651Z","end":"2026-09-08T15:49:49.992533Z","steps":["trace[342058263] 'process raft request' (duration: 48.067981ms)","trace[342058263] 'compare' (duration: 64.679962ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:53.360545Z","caller":"traceutil/trace.go:171","msg":"trace[602469701] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"109.037278ms","start":"2026-09-08T15:49:53.251448Z","end":"2026-09-08T15:49:53.360485Z","steps":["trace[602469701] 'read index received' (duration: 108.874533ms)","trace[602469701] 'applied index is now lower than readState.Index' (duration: 160.935µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:53.360765Z","caller":"traceutil/trace.go:171","msg":"trace[574446633] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"204.416298ms","start":"2026-09-08T15:49:53.156169Z","end":"2026-09-08T15:49:53.360585Z","steps":["trace[574446633] 'process raft request' (duration: 204.1099ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:53.360838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.338116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-08T15:49:53.361061Z","caller":"traceutil/trace.go:171","msg":"trace[1720473168] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1221; }","duration":"109.656245ms","start":"2026-09-08T15:49:53.251387Z","end":"2026-09-08T15:49:53.361043Z","steps":["trace[1720473168] 'agreement among raft nodes before linearized reading' (duration: 109.254914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:53.694452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.385346ms","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-09-08T15:49:53.694633Z","caller":"traceutil/trace.go:171","msg":"trace[830308983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1221; }","duration":"104.635483ms","start":"2026-09-08T15:49:53.589975Z","end":"2026-09-08T15:49:53.69461Z","steps":["trace[830308983] 'range keys from in-memory index tree' (duration: 104.15348ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:54.126647Z","caller":"traceutil/trace.go:171","msg":"trace[26759880] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"427.010372ms","start":"2026-09-08T15:49:53.699615Z","end":"2026-09-08T15:49:54.126626Z","steps":["trace[26759880] 'process raft request' (duration: 426.894019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:54.126791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:53.699566Z","time spent":"427.159687ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:49:54.684989Z","caller":"traceutil/trace.go:171","msg":"trace[97581694] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"645.936153ms","start":"2026-09-08T15:49:54.039017Z","end":"2026-09-08T15:49:54.684953Z","steps":["trace[97581694] 'read index received' (duration: 87.661973ms)","trace[97581694] 'applied index is now lower than readState.Index' (duration: 558.27283ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:54.685187Z","caller":"traceutil/trace.go:171","msg":"trace[1665381962] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"963.301631ms","start":"2026-09-08T15:49:53.72187Z","end":"2026-09-08T15:49:54.685172Z","steps":["trace[1665381962] 'process raft request' (duration: 881.058481ms)","trace[1665381962] 'compare' (duration: 81.827797ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:54.685477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.089825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-08T15:49:54.685527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.588906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:49:54.685558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:53.721838Z","time spent":"963.380863ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:49:54.685569Z","caller":"traceutil/trace.go:171","msg":"trace[1485621901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1223; }","duration":"570.213889ms","start":"2026-09-08T15:49:54.115332Z","end":"2026-09-08T15:49:54.685546Z","steps":["trace[1485621901] 'agreement among raft nodes before linearized reading' (duration: 569.953932ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:54.685623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:54.115317Z","time spent":"570.297251ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-08T15:49:54.685625Z","caller":"traceutil/trace.go:171","msg":"trace[1681435685] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1223; }","duration":"102.753021ms","start":"2026-09-08T15:49:54.582841Z","end":"2026-09-08T15:49:54.685594Z","steps":["trace[1681435685] 'agreement among raft nodes before linearized reading' (duration: 102.607896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:54.685702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.820655ms","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-09-08T15:49:54.685777Z","caller":"traceutil/trace.go:171","msg":"trace[2094664778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1223; }","duration":"285.936937ms","start":"2026-09-08T15:49:54.399822Z","end":"2026-09-08T15:49:54.685759Z","steps":["trace[2094664778] 'agreement among raft nodes before linearized reading' (duration: 285.66499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:54.685732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.677964ms","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-09-08T15:49:54.685849Z","caller":"traceutil/trace.go:171","msg":"trace[1274969769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1223; }","duration":"646.843319ms","start":"2026-09-08T15:49:54.038977Z","end":"2026-09-08T15:49:54.68582Z","steps":["trace[1274969769] 'agreement among raft nodes before linearized reading' (duration: 646.481099ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:54.685919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:54.038958Z","time spent":"646.948371ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-08T15:49:54.909143Z","caller":"traceutil/trace.go:171","msg":"trace[733437426] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"218.089115ms","start":"2026-09-08T15:49:54.690998Z","end":"2026-09-08T15:49:54.909087Z","steps":["trace[733437426] 'process raft request' (duration: 122.90414ms)","trace[733437426] 'compare' (duration: 94.856796ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:54.909225Z","caller":"traceutil/trace.go:171","msg":"trace[315238857] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"218.038995ms","start":"2026-09-08T15:49:54.691135Z","end":"2026-09-08T15:49:54.909174Z","steps":["trace[315238857] 'process raft request' (duration: 217.824288ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:54.909082Z","caller":"traceutil/trace.go:171","msg":"trace[1886940362] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1269; }","duration":"212.712703ms","start":"2026-09-08T15:49:54.696332Z","end":"2026-09-08T15:49:54.909044Z","steps":["trace[1886940362] 'read index received' (duration: 5.287909ms)","trace[1886940362] 'applied index is now lower than readState.Index' (duration: 207.422644ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:54.909317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.922929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:49:54.909381Z","caller":"traceutil/trace.go:171","msg":"trace[908461305] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1226; }","duration":"213.041383ms","start":"2026-09-08T15:49:54.696321Z","end":"2026-09-08T15:49:54.909362Z","steps":["trace[908461305] 'agreement among raft nodes before linearized reading' (duration: 212.860308ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:08.885859Z","caller":"traceutil/trace.go:171","msg":"trace[2002739608] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"106.368783ms","start":"2026-09-08T15:50:08.779435Z","end":"2026-09-08T15:50:08.885804Z","steps":["trace[2002739608] 'process raft request' (duration: 106.061604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:16.200339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.140617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:50:16.200434Z","caller":"traceutil/trace.go:171","msg":"trace[2020462207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1478; }","duration":"309.293022ms","start":"2026-09-08T15:50:15.891125Z","end":"2026-09-08T15:50:16.200418Z","steps":["trace[2020462207] 'range keys from in-memory index tree' (duration: 309.025284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:16.200479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:50:15.891081Z","time spent":"309.391384ms","remote":"127.0.0.1:40158","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-09-08T15:50:59.314542Z","caller":"traceutil/trace.go:171","msg":"trace[1582762427] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"156.647557ms","start":"2026-09-08T15:50:59.157868Z","end":"2026-09-08T15:50:59.314516Z","steps":["trace[1582762427] 'process raft request' (duration: 156.483982ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:59.401014Z","caller":"traceutil/trace.go:171","msg":"trace[773090910] linearizableReadLoop","detail":"{readStateIndex:2113; appliedIndex:2111; }","duration":"230.103817ms","start":"2026-09-08T15:50:59.170881Z","end":"2026-09-08T15:50:59.400985Z","steps":["trace[773090910] 'read index received' (duration: 143.573427ms)","trace[773090910] 'applied index is now lower than readState.Index' (duration: 86.52905ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:50:59.401254Z","caller":"traceutil/trace.go:171","msg":"trace[696467443] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"241.133649ms","start":"2026-09-08T15:50:59.160079Z","end":"2026-09-08T15:50:59.401212Z","steps":["trace[696467443] 'process raft request' (duration: 202.556521ms)","trace[696467443] 'compare' (duration: 38.179737ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:50:59.401374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.458758ms","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-09-08T15:50:59.40142Z","caller":"traceutil/trace.go:171","msg":"trace[1977858095] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2028; }","duration":"230.55049ms","start":"2026-09-08T15:50:59.170857Z","end":"2026-09-08T15:50:59.401407Z","steps":["trace[1977858095] 'agreement among raft nodes before linearized reading' (duration: 230.451667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:59.401446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.204114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-08T15:50:59.401531Z","caller":"traceutil/trace.go:171","msg":"trace[737471315] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2028; }","duration":"228.325707ms","start":"2026-09-08T15:50:59.173187Z","end":"2026-09-08T15:50:59.401512Z","steps":["trace[737471315] 'agreement among raft nodes before linearized reading' (duration: 228.145472ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:02.901187Z","caller":"traceutil/trace.go:171","msg":"trace[773800597] linearizableReadLoop","detail":"{readStateIndex:2132; appliedIndex:2131; }","duration":"101.965985ms","start":"2026-09-08T15:51:02.799184Z","end":"2026-09-08T15:51:02.90115Z","steps":["trace[773800597] 'read index received' (duration: 101.682147ms)","trace[773800597] 'applied index is now lower than readState.Index' (duration: 282.588µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:51:02.90136Z","caller":"traceutil/trace.go:171","msg":"trace[705805486] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"165.856627ms","start":"2026-09-08T15:51:02.735433Z","end":"2026-09-08T15:51:02.901289Z","steps":["trace[705805486] 'process raft request' (duration: 165.476776ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:02.901481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.266023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-08T15:51:02.901554Z","caller":"traceutil/trace.go:171","msg":"trace[1321493101] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2046; }","duration":"102.395537ms","start":"2026-09-08T15:51:02.799141Z","end":"2026-09-08T15:51:02.901537Z","steps":["trace[1321493101] 'agreement among raft nodes before linearized reading' (duration: 102.16206ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:35.80139Z","caller":"traceutil/trace.go:171","msg":"trace[795277600] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"306.091472ms","start":"2026-09-08T15:51:35.495257Z","end":"2026-09-08T15:51:35.801348Z","steps":["trace[795277600] 'process raft request' (duration: 305.807253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:35.801631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:35.495236Z","time spent":"306.266606ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:51:35.97002Z","caller":"traceutil/trace.go:171","msg":"trace[172344744] linearizableReadLoop","detail":"{readStateIndex:2320; appliedIndex:2318; }","duration":"372.577645ms","start":"2026-09-08T15:51:35.597397Z","end":"2026-09-08T15:51:35.969974Z","steps":["trace[172344744] 'read index received' (duration: 203.695672ms)","trace[172344744] 'applied index is now lower than readState.Index' (duration: 168.880853ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:51:35.970182Z","caller":"traceutil/trace.go:171","msg":"trace[237771923] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"410.280569ms","start":"2026-09-08T15:51:35.559887Z","end":"2026-09-08T15:51:35.970168Z","steps":["trace[237771923] 'process raft request' (duration: 317.608366ms)","trace[237771923] 'compare' (duration: 92.356334ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:51:35.970291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:35.559861Z","time spent":"410.346831ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:51:35.970519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.945344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.57\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-09-08T15:51:35.970601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.033823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-08T15:51:35.970615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.584753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:51:35.970604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.748737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-08T15:51:35.970646Z","caller":"traceutil/trace.go:171","msg":"trace[1865193988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2227; }","duration":"348.119755ms","start":"2026-09-08T15:51:35.622513Z","end":"2026-09-08T15:51:35.970632Z","steps":["trace[1865193988] 'agreement among raft nodes before linearized reading' (duration: 347.985431ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:35.970631Z","caller":"traceutil/trace.go:171","msg":"trace[2053164681] range","detail":"{range_begin:/registry/masterleases/162.253.55.57; range_end:; response_count:1; response_revision:2227; }","duration":"191.104078ms","start":"2026-09-08T15:51:35.779497Z","end":"2026-09-08T15:51:35.970601Z","steps":["trace[2053164681] 'agreement among raft nodes before linearized reading' (duration: 190.82429ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:35.970651Z","caller":"traceutil/trace.go:171","msg":"trace[1208564316] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2227; }","duration":"113.658925ms","start":"2026-09-08T15:51:35.856978Z","end":"2026-09-08T15:51:35.970637Z","steps":["trace[1208564316] 'agreement among raft nodes before linearized reading' (duration: 113.604384ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:35.97066Z","caller":"traceutil/trace.go:171","msg":"trace[1522308740] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2227; }","duration":"346.847169ms","start":"2026-09-08T15:51:35.623799Z","end":"2026-09-08T15:51:35.970646Z","steps":["trace[1522308740] 'agreement among raft nodes before linearized reading' (duration: 346.682385ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:35.970665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:35.622493Z","time spent":"348.166977ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-08T15:51:35.97052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.413783ms","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-09-08T15:51:35.970674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.272695ms","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-09-08T15:51:35.970759Z","caller":"traceutil/trace.go:171","msg":"trace[2011171810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2227; }","duration":"373.401738ms","start":"2026-09-08T15:51:35.597337Z","end":"2026-09-08T15:51:35.970739Z","steps":["trace[2011171810] 'agreement among raft nodes before linearized reading' (duration: 373.12016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:35.970659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.199243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:51:35.970804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:35.597321Z","time spent":"373.470371ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-08T15:51:35.970835Z","caller":"traceutil/trace.go:171","msg":"trace[816459634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2227; }","duration":"284.398348ms","start":"2026-09-08T15:51:35.686409Z","end":"2026-09-08T15:51:35.970807Z","steps":["trace[816459634] 'agreement among raft nodes before linearized reading' (duration: 284.171182ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:35.970832Z","caller":"traceutil/trace.go:171","msg":"trace[1478395507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2227; }","duration":"365.714511ms","start":"2026-09-08T15:51:35.605034Z","end":"2026-09-08T15:51:35.970749Z","steps":["trace[1478395507] 'agreement among raft nodes before linearized reading' (duration: 365.29137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:35.970689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:35.623778Z","time spent":"346.902951ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-08T15:51:35.971034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:35.605014Z","time spent":"365.949137ms","remote":"127.0.0.1:40442","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-09-08T15:51:41.351695Z","caller":"traceutil/trace.go:171","msg":"trace[1657550785] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"221.998849ms","start":"2026-09-08T15:51:41.129661Z","end":"2026-09-08T15:51:41.351659Z","steps":["trace[1657550785] 'process raft request' (duration: 221.761183ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:41.448171Z","caller":"traceutil/trace.go:171","msg":"trace[1272668576] linearizableReadLoop","detail":"{readStateIndex:2350; appliedIndex:2348; }","duration":"251.265154ms","start":"2026-09-08T15:51:41.196875Z","end":"2026-09-08T15:51:41.44814Z","steps":["trace[1272668576] 'read index received' (duration: 154.582479ms)","trace[1272668576] 'applied index is now lower than readState.Index' (duration: 96.680625ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:51:41.448301Z","caller":"traceutil/trace.go:171","msg":"trace[445733065] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"155.665409ms","start":"2026-09-08T15:51:41.292615Z","end":"2026-09-08T15:51:41.44828Z","steps":["trace[445733065] 'process raft request' (duration: 155.068222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:41.522629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.718144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-08T15:51:41.522734Z","caller":"traceutil/trace.go:171","msg":"trace[1743359553] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2256; }","duration":"325.886178ms","start":"2026-09-08T15:51:41.196819Z","end":"2026-09-08T15:51:41.522705Z","steps":["trace[1743359553] 'agreement among raft nodes before linearized reading' (duration: 251.431828ms)","trace[1743359553] 'range keys from in-memory index tree' (duration: 74.195783ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:51:41.522784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:41.196799Z","time spent":"325.974261ms","remote":"127.0.0.1:34252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-08T15:52:16.511614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.425733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-08T15:52:16.511731Z","caller":"traceutil/trace.go:171","msg":"trace[308394794] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2627; }","duration":"172.582787ms","start":"2026-09-08T15:52:16.339122Z","end":"2026-09-08T15:52:16.511705Z","steps":["trace[308394794] 'range keys from in-memory index tree' (duration: 172.245708ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:00.745132Z","caller":"traceutil/trace.go:171","msg":"trace[1279099898] transaction","detail":"{read_only:false; response_revision:2888; number_of_response:1; }","duration":"188.077104ms","start":"2026-09-08T15:53:00.557028Z","end":"2026-09-08T15:53:00.745105Z","steps":["trace[1279099898] 'process raft request' (duration: 121.518977ms)","trace[1279099898] 'compare' (duration: 66.343131ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:53:00.745479Z","caller":"traceutil/trace.go:171","msg":"trace[2068041923] transaction","detail":"{read_only:false; response_revision:2889; number_of_response:1; }","duration":"187.97846ms","start":"2026-09-08T15:53:00.557412Z","end":"2026-09-08T15:53:00.745391Z","steps":["trace[2068041923] 'process raft request' (duration: 187.61276ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:55:51.597905Z","caller":"traceutil/trace.go:171","msg":"trace[29134820] transaction","detail":"{read_only:false; response_revision:3912; number_of_response:1; }","duration":"157.69679ms","start":"2026-09-08T15:55:51.440179Z","end":"2026-09-08T15:55:51.597875Z","steps":["trace[29134820] 'process raft request' (duration: 139.42935ms)","trace[29134820] 'compare' (duration: 18.130876ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:55:51.598637Z","caller":"traceutil/trace.go:171","msg":"trace[2009233537] linearizableReadLoop","detail":"{readStateIndex:4073; appliedIndex:4071; }","duration":"127.25208ms","start":"2026-09-08T15:55:51.47136Z","end":"2026-09-08T15:55:51.598612Z","steps":["trace[2009233537] 'read index received' (duration: 108.343683ms)","trace[2009233537] 'applied index is now lower than readState.Index' (duration: 18.907067ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:55:51.598726Z","caller":"traceutil/trace.go:171","msg":"trace[1328711301] transaction","detail":"{read_only:false; response_revision:3913; number_of_response:1; }","duration":"152.053193ms","start":"2026-09-08T15:55:51.446651Z","end":"2026-09-08T15:55:51.598704Z","steps":["trace[1328711301] 'process raft request' (duration: 151.875857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:55:51.599064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.691593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-09-08T15:55:51.599391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.625997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-08T15:55:51.599442Z","caller":"traceutil/trace.go:171","msg":"trace[415427695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3913; }","duration":"110.74341ms","start":"2026-09-08T15:55:51.488687Z","end":"2026-09-08T15:55:51.59943Z","steps":["trace[415427695] 'agreement among raft nodes before linearized reading' (duration: 110.575436ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:55:51.599404Z","caller":"traceutil/trace.go:171","msg":"trace[1062980183] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3913; }","duration":"128.087804ms","start":"2026-09-08T15:55:51.471299Z","end":"2026-09-08T15:55:51.599387Z","steps":["trace[1062980183] 'agreement among raft nodes before linearized reading' (duration: 127.436546ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:56:18.731618Z","caller":"traceutil/trace.go:171","msg":"trace[95553798] transaction","detail":"{read_only:false; response_revision:4124; number_of_response:1; }","duration":"139.302687ms","start":"2026-09-08T15:56:18.592273Z","end":"2026-09-08T15:56:18.731576Z","steps":["trace[95553798] 'process raft request' (duration: 64.355306ms)","trace[95553798] 'compare' (duration: 74.685234ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:56:24.016714Z","caller":"traceutil/trace.go:171","msg":"trace[1113731945] linearizableReadLoop","detail":"{readStateIndex:4316; appliedIndex:4315; }","duration":"233.515646ms","start":"2026-09-08T15:56:23.783167Z","end":"2026-09-08T15:56:24.016682Z","steps":["trace[1113731945] 'read index received' (duration: 233.341341ms)","trace[1113731945] 'applied index is now lower than readState.Index' (duration: 173.105µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:56:24.017055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.792044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fdft4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-08T15:56:24.017172Z","caller":"traceutil/trace.go:171","msg":"trace[1119710660] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fdft4; range_end:; response_count:1; response_revision:4150; }","duration":"234.114263ms","start":"2026-09-08T15:56:23.783041Z","end":"2026-09-08T15:56:24.017156Z","steps":["trace[1119710660] 'agreement among raft nodes before linearized reading' (duration: 233.780084ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:56:24.01678Z","caller":"traceutil/trace.go:171","msg":"trace[161855968] transaction","detail":"{read_only:false; response_revision:4150; number_of_response:1; }","duration":"234.344909ms","start":"2026-09-08T15:56:23.78241Z","end":"2026-09-08T15:56:24.016755Z","steps":["trace[161855968] 'process raft request' (duration: 234.059541ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:56:24.145051Z","caller":"traceutil/trace.go:171","msg":"trace[2018263754] linearizableReadLoop","detail":"{readStateIndex:4317; appliedIndex:4316; }","duration":"128.229997ms","start":"2026-09-08T15:56:24.016799Z","end":"2026-09-08T15:56:24.145029Z","steps":["trace[2018263754] 'read index received' (duration: 124.138953ms)","trace[2018263754] 'applied index is now lower than readState.Index' (duration: 4.090124ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:56:24.145104Z","caller":"traceutil/trace.go:171","msg":"trace[804659795] transaction","detail":"{read_only:false; response_revision:4151; number_of_response:1; }","duration":"358.320707ms","start":"2026-09-08T15:56:23.786746Z","end":"2026-09-08T15:56:24.145066Z","steps":["trace[804659795] 'process raft request' (duration: 354.205813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:56:24.145282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:56:23.786728Z","time spent":"358.454261ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:56:24.145338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.562223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-09-08T15:56:24.145384Z","caller":"traceutil/trace.go:171","msg":"trace[1562691189] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:4151; }","duration":"178.703206ms","start":"2026-09-08T15:56:23.966664Z","end":"2026-09-08T15:56:24.145367Z","steps":["trace[1562691189] 'agreement among raft nodes before linearized reading' (duration: 178.49007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:56:24.145716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.630996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-09-08T15:56:24.145697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.061292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62868"} {"level":"warn","ts":"2026-09-08T15:56:24.145729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.880978ms","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-09-08T15:56:24.145772Z","caller":"traceutil/trace.go:171","msg":"trace[990070222] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4151; }","duration":"206.680867ms","start":"2026-09-08T15:56:23.939073Z","end":"2026-09-08T15:56:24.145754Z","steps":["trace[990070222] 'agreement among raft nodes before linearized reading' (duration: 206.517902ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:56:24.145779Z","caller":"traceutil/trace.go:171","msg":"trace[1144836444] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4151; }","duration":"124.170875ms","start":"2026-09-08T15:56:24.021592Z","end":"2026-09-08T15:56:24.145763Z","steps":["trace[1144836444] 'agreement among raft nodes before linearized reading' (duration: 123.924428ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:56:24.145807Z","caller":"traceutil/trace.go:171","msg":"trace[608208396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4151; }","duration":"285.010682ms","start":"2026-09-08T15:56:23.860778Z","end":"2026-09-08T15:56:24.145789Z","steps":["trace[608208396] 'agreement among raft nodes before linearized reading' (duration: 284.737614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:53.250253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.587168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-patch-8lppd\" ","response":"range_response_count:1 size:4331"} {"level":"info","ts":"2026-09-08T15:57:53.250353Z","caller":"traceutil/trace.go:171","msg":"trace[895160117] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-patch-8lppd; range_end:; response_count:1; response_revision:5121; }","duration":"135.751453ms","start":"2026-09-08T15:57:53.114576Z","end":"2026-09-08T15:57:53.250328Z","steps":["trace[895160117] 'range keys from in-memory index tree' (duration: 135.359572ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:53.250466Z","caller":"traceutil/trace.go:171","msg":"trace[1099986230] linearizableReadLoop","detail":"{readStateIndex:5325; appliedIndex:5324; }","duration":"127.151661ms","start":"2026-09-08T15:57:53.12329Z","end":"2026-09-08T15:57:53.250442Z","steps":["trace[1099986230] 'read index received' (duration: 107.8955ms)","trace[1099986230] 'applied index is now lower than readState.Index' (duration: 19.254671ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:57:53.250667Z","caller":"traceutil/trace.go:171","msg":"trace[1135775524] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"135.63633ms","start":"2026-09-08T15:57:53.114997Z","end":"2026-09-08T15:57:53.250633Z","steps":["trace[1135775524] 'process raft request' (duration: 116.208044ms)","trace[1135775524] 'compare' (duration: 18.897001ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:57:53.251615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.302893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210289"} {"level":"info","ts":"2026-09-08T15:57:53.251671Z","caller":"traceutil/trace.go:171","msg":"trace[2146388894] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5122; }","duration":"128.409056ms","start":"2026-09-08T15:57:53.123247Z","end":"2026-09-08T15:57:53.251656Z","steps":["trace[2146388894] 'agreement among raft nodes before linearized reading' (duration: 127.278425ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:02.315194Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2894} {"level":"info","ts":"2026-09-08T15:58:02.397815Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2894,"took":"81.241892ms","hash":1957836039,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":28831744,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-08T15:58:02.397895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1957836039,"revision":2894,"compact-revision":-1} {"level":"warn","ts":"2026-09-08T15:58:06.041004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.284743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:58:06.041056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.580365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:58:06.041125Z","caller":"traceutil/trace.go:171","msg":"trace[1863203833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5397; }","duration":"149.496179ms","start":"2026-09-08T15:58:05.891591Z","end":"2026-09-08T15:58:06.041087Z","steps":["trace[1863203833] 'range keys from in-memory index tree' (duration: 149.15345ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:06.041164Z","caller":"traceutil/trace.go:171","msg":"trace[1712280728] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:5397; }","duration":"226.75407ms","start":"2026-09-08T15:58:05.814383Z","end":"2026-09-08T15:58:06.041137Z","steps":["trace[1712280728] 'count revisions from in-memory index tree' (duration: 226.457042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:06.04122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.813248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:58:06.041326Z","caller":"traceutil/trace.go:171","msg":"trace[956000393] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5397; }","duration":"207.944481ms","start":"2026-09-08T15:58:05.833364Z","end":"2026-09-08T15:58:06.041308Z","steps":["trace[956000393] 'range keys from in-memory index tree' (duration: 207.715705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:06.04159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.999233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f81fc714-8bb9-48a4-a19f-6557edbd3d09\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-09-08T15:58:06.041682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.839345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-08T15:58:06.041795Z","caller":"traceutil/trace.go:171","msg":"trace[787944264] range","detail":"{range_begin:/registry/masterleases/162.253.55.57; range_end:; response_count:1; response_revision:5397; }","duration":"236.0239ms","start":"2026-09-08T15:58:05.805744Z","end":"2026-09-08T15:58:06.041768Z","steps":["trace[787944264] 'range keys from in-memory index tree' (duration: 235.498986ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:06.041711Z","caller":"traceutil/trace.go:171","msg":"trace[1240889582] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f81fc714-8bb9-48a4-a19f-6557edbd3d09; range_end:; response_count:1; response_revision:5397; }","duration":"271.177078ms","start":"2026-09-08T15:58:05.770505Z","end":"2026-09-08T15:58:06.041682Z","steps":["trace[1240889582] 'range keys from in-memory index tree' (duration: 270.750547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:06.53693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.041808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:58:06.537078Z","caller":"traceutil/trace.go:171","msg":"trace[2145661210] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"213.333743ms","start":"2026-09-08T15:58:06.32372Z","end":"2026-09-08T15:58:06.537053Z","steps":["trace[2145661210] 'process raft request' (duration: 80.091559ms)","trace[2145661210] 'compare' (duration: 132.877954ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:06.571578Z","caller":"traceutil/trace.go:171","msg":"trace[401716111] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"246.33851ms","start":"2026-09-08T15:58:06.325182Z","end":"2026-09-08T15:58:06.57152Z","steps":["trace[401716111] 'process raft request' (duration: 246.200396ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:22.953816Z","caller":"traceutil/trace.go:171","msg":"trace[1926186157] linearizableReadLoop","detail":"{readStateIndex:5922; appliedIndex:5921; }","duration":"270.952052ms","start":"2026-09-08T15:58:22.682838Z","end":"2026-09-08T15:58:22.95379Z","steps":["trace[1926186157] 'read index received' (duration: 270.698385ms)","trace[1926186157] 'applied index is now lower than readState.Index' (duration: 252.897µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:58:22.954047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.182778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:58:22.954144Z","caller":"traceutil/trace.go:171","msg":"trace[2038894477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5698; }","duration":"271.341502ms","start":"2026-09-08T15:58:22.682778Z","end":"2026-09-08T15:58:22.95412Z","steps":["trace[2038894477] 'agreement among raft nodes before linearized reading' (duration: 271.144957ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:22.954165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.269191ms","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-09-08T15:58:22.954206Z","caller":"traceutil/trace.go:171","msg":"trace[952256466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5698; }","duration":"181.367713ms","start":"2026-09-08T15:58:22.772827Z","end":"2026-09-08T15:58:22.954195Z","steps":["trace[952256466] 'agreement among raft nodes before linearized reading' (duration: 181.179668ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:22.954586Z","caller":"traceutil/trace.go:171","msg":"trace[1828434325] transaction","detail":"{read_only:false; response_revision:5698; number_of_response:1; }","duration":"472.856403ms","start":"2026-09-08T15:58:22.481048Z","end":"2026-09-08T15:58:22.953905Z","steps":["trace[1828434325] 'process raft request' (duration: 472.564085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:22.95485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.33651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T15:58:22.954908Z","caller":"traceutil/trace.go:171","msg":"trace[1210633718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5698; }","duration":"168.397882ms","start":"2026-09-08T15:58:22.786493Z","end":"2026-09-08T15:58:22.954891Z","steps":["trace[1210633718] 'agreement among raft nodes before linearized reading' (duration: 168.01192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:22.954919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:58:22.481023Z","time spent":"473.813081ms","remote":"127.0.0.1:40336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8040,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:58:23.212219Z","caller":"traceutil/trace.go:171","msg":"trace[307303804] transaction","detail":"{read_only:false; response_revision:5699; number_of_response:1; }","duration":"252.309188ms","start":"2026-09-08T15:58:22.959883Z","end":"2026-09-08T15:58:23.212192Z","steps":["trace[307303804] 'process raft request' (duration: 180.053289ms)","trace[307303804] 'compare' (duration: 72.117495ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:23.23155Z","caller":"traceutil/trace.go:171","msg":"trace[1874491289] transaction","detail":"{read_only:false; response_revision:5701; number_of_response:1; }","duration":"265.757276ms","start":"2026-09-08T15:58:22.965763Z","end":"2026-09-08T15:58:23.23152Z","steps":["trace[1874491289] 'process raft request' (duration: 265.639783ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:23.231551Z","caller":"traceutil/trace.go:171","msg":"trace[833326635] transaction","detail":"{read_only:false; response_revision:5700; number_of_response:1; }","duration":"267.209046ms","start":"2026-09-08T15:58:22.964315Z","end":"2026-09-08T15:58:23.231524Z","steps":["trace[833326635] 'process raft request' (duration: 266.929248ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:23.232009Z","caller":"traceutil/trace.go:171","msg":"trace[101067907] transaction","detail":"{read_only:false; response_revision:5702; number_of_response:1; }","duration":"265.498748ms","start":"2026-09-08T15:58:22.966457Z","end":"2026-09-08T15:58:23.231956Z","steps":["trace[101067907] 'process raft request' (duration: 265.012695ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:23.232236Z","caller":"traceutil/trace.go:171","msg":"trace[1637215741] transaction","detail":"{read_only:false; response_revision:5703; number_of_response:1; }","duration":"265.652823ms","start":"2026-09-08T15:58:22.96654Z","end":"2026-09-08T15:58:23.232193Z","steps":["trace[1637215741] 'process raft request' (duration: 265.21151ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:23.232631Z","caller":"traceutil/trace.go:171","msg":"trace[343255008] transaction","detail":"{read_only:false; response_revision:5704; number_of_response:1; }","duration":"266.041344ms","start":"2026-09-08T15:58:22.966574Z","end":"2026-09-08T15:58:23.232616Z","steps":["trace[343255008] 'process raft request' (duration: 265.503138ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:23.233176Z","caller":"traceutil/trace.go:171","msg":"trace[205971073] linearizableReadLoop","detail":"{readStateIndex:5924; appliedIndex:5922; }","duration":"268.100862ms","start":"2026-09-08T15:58:22.965051Z","end":"2026-09-08T15:58:23.233151Z","steps":["trace[205971073] 'read index received' (duration: 174.910405ms)","trace[205971073] 'applied index is now lower than readState.Index' (duration: 93.188937ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:23.233246Z","caller":"traceutil/trace.go:171","msg":"trace[243979511] transaction","detail":"{read_only:false; response_revision:5706; number_of_response:1; }","duration":"265.90337ms","start":"2026-09-08T15:58:22.96732Z","end":"2026-09-08T15:58:23.233223Z","steps":["trace[243979511] 'process raft request' (duration: 265.637763ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:23.233309Z","caller":"traceutil/trace.go:171","msg":"trace[832045346] transaction","detail":"{read_only:false; response_revision:5705; number_of_response:1; }","duration":"266.424304ms","start":"2026-09-08T15:58:22.966862Z","end":"2026-09-08T15:58:23.233286Z","steps":["trace[832045346] 'process raft request' (duration: 265.661803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:23.233545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.473052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70916"} {"level":"info","ts":"2026-09-08T15:58:23.233605Z","caller":"traceutil/trace.go:171","msg":"trace[71643533] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5706; }","duration":"268.560115ms","start":"2026-09-08T15:58:22.965029Z","end":"2026-09-08T15:58:23.233589Z","steps":["trace[71643533] 'agreement among raft nodes before linearized reading' (duration: 268.197805ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:31.659596Z","caller":"traceutil/trace.go:171","msg":"trace[546040654] transaction","detail":"{read_only:false; response_revision:5890; number_of_response:1; }","duration":"129.890339ms","start":"2026-09-08T15:58:31.529645Z","end":"2026-09-08T15:58:31.659535Z","steps":["trace[546040654] 'process raft request' (duration: 96.373358ms)","trace[546040654] 'compare' (duration: 32.179723ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:31.903174Z","caller":"traceutil/trace.go:171","msg":"trace[1555905151] linearizableReadLoop","detail":"{readStateIndex:6121; appliedIndex:6120; }","duration":"176.748705ms","start":"2026-09-08T15:58:31.726376Z","end":"2026-09-08T15:58:31.903125Z","steps":["trace[1555905151] 'read index received' (duration: 176.408676ms)","trace[1555905151] 'applied index is now lower than readState.Index' (duration: 337.869µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:31.903276Z","caller":"traceutil/trace.go:171","msg":"trace[857308367] transaction","detail":"{read_only:false; response_revision:5892; number_of_response:1; }","duration":"197.431616ms","start":"2026-09-08T15:58:31.705805Z","end":"2026-09-08T15:58:31.903237Z","steps":["trace[857308367] 'process raft request' (duration: 197.024145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:31.903479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.056494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:58:31.903573Z","caller":"traceutil/trace.go:171","msg":"trace[916716361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5892; }","duration":"177.219848ms","start":"2026-09-08T15:58:31.726335Z","end":"2026-09-08T15:58:31.903555Z","steps":["trace[916716361] 'agreement among raft nodes before linearized reading' (duration: 177.030873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:31.903571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.346106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-08T15:58:31.903632Z","caller":"traceutil/trace.go:171","msg":"trace[911219548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5892; }","duration":"170.444739ms","start":"2026-09-08T15:58:31.733167Z","end":"2026-09-08T15:58:31.903612Z","steps":["trace[911219548] 'agreement among raft nodes before linearized reading' (duration: 170.258484ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:59:24.645195Z","caller":"traceutil/trace.go:171","msg":"trace[1609213712] transaction","detail":"{read_only:false; response_revision:6367; number_of_response:1; }","duration":"114.477742ms","start":"2026-09-08T15:59:24.530684Z","end":"2026-09-08T15:59:24.645162Z","steps":["trace[1609213712] 'process raft request' (duration: 114.252525ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:03:02.322928Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5278} {"level":"info","ts":"2026-09-08T16:03:02.40747Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5278,"took":"82.942537ms","hash":2829374535,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":27926528,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-08T16:03:02.407549Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2829374535,"revision":5278,"compact-revision":2894} {"level":"info","ts":"2026-09-08T16:04:41.560548Z","caller":"traceutil/trace.go:171","msg":"trace[229285215] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"132.080725ms","start":"2026-09-08T16:04:41.428398Z","end":"2026-09-08T16:04:41.560479Z","steps":["trace[229285215] 'process raft request' (duration: 115.356047ms)","trace[229285215] 'compare' (duration: 16.520102ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:04:41.561218Z","caller":"traceutil/trace.go:171","msg":"trace[1281225932] transaction","detail":"{read_only:false; response_revision:8702; number_of_response:1; }","duration":"104.02009ms","start":"2026-09-08T16:04:41.457177Z","end":"2026-09-08T16:04:41.561197Z","steps":["trace[1281225932] 'process raft request' (duration: 103.250148ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:43.850878Z","caller":"traceutil/trace.go:171","msg":"trace[1333152922] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"341.829301ms","start":"2026-09-08T16:04:43.509028Z","end":"2026-09-08T16:04:43.850857Z","steps":["trace[1333152922] 'process raft request' (duration: 341.700948ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:43.850922Z","caller":"traceutil/trace.go:171","msg":"trace[1642346818] linearizableReadLoop","detail":"{readStateIndex:9029; appliedIndex:9029; }","duration":"285.944788ms","start":"2026-09-08T16:04:43.564922Z","end":"2026-09-08T16:04:43.850866Z","steps":["trace[1642346818] 'read index received' (duration: 285.927358ms)","trace[1642346818] 'applied index is now lower than readState.Index' (duration: 14.19µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:04:43.851019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:43.508996Z","time spent":"341.954825ms","remote":"127.0.0.1:40442","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-09-08T16:04:43.851581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.611438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-08T16:04:43.851705Z","caller":"traceutil/trace.go:171","msg":"trace[1728831747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8710; }","duration":"286.805903ms","start":"2026-09-08T16:04:43.564866Z","end":"2026-09-08T16:04:43.851672Z","steps":["trace[1728831747] 'agreement among raft nodes before linearized reading' (duration: 286.146404ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:44.068104Z","caller":"traceutil/trace.go:171","msg":"trace[989743262] linearizableReadLoop","detail":"{readStateIndex:9030; appliedIndex:9029; }","duration":"217.074363ms","start":"2026-09-08T16:04:43.850996Z","end":"2026-09-08T16:04:44.06807Z","steps":["trace[989743262] 'read index received' (duration: 173.577966ms)","trace[989743262] 'applied index is now lower than readState.Index' (duration: 43.494977ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:04:44.068233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.358381ms","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-09-08T16:04:44.068301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.62781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.068327Z","caller":"traceutil/trace.go:171","msg":"trace[1546432012] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8711; }","duration":"246.523656ms","start":"2026-09-08T16:04:43.821784Z","end":"2026-09-08T16:04:44.068308Z","steps":["trace[1546432012] 'agreement among raft nodes before linearized reading' (duration: 246.30213ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:44.068342Z","caller":"traceutil/trace.go:171","msg":"trace[1197725657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8711; }","duration":"383.756274ms","start":"2026-09-08T16:04:43.684574Z","end":"2026-09-08T16:04:44.06833Z","steps":["trace[1197725657] 'agreement among raft nodes before linearized reading' (duration: 383.654601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.068317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.00699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-09-08T16:04:44.068382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:43.684532Z","time spent":"383.842216ms","remote":"127.0.0.1:40158","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-09-08T16:04:44.068408Z","caller":"traceutil/trace.go:171","msg":"trace[294034891] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8711; }","duration":"365.152733ms","start":"2026-09-08T16:04:43.703233Z","end":"2026-09-08T16:04:44.068385Z","steps":["trace[294034891] 'agreement among raft nodes before linearized reading' (duration: 364.886956ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.068449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:43.703204Z","time spent":"365.236906ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-08T16:04:44.068542Z","caller":"traceutil/trace.go:171","msg":"trace[1195038850] transaction","detail":"{read_only:false; response_revision:8711; number_of_response:1; }","duration":"501.554509ms","start":"2026-09-08T16:04:43.566924Z","end":"2026-09-08T16:04:44.068479Z","steps":["trace[1195038850] 'process raft request' (duration: 457.62815ms)","trace[1195038850] 'compare' (duration: 43.366953ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:04:44.06867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:43.566913Z","time spent":"501.684483ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:04:44.068776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.621636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T16:04:44.068846Z","caller":"traceutil/trace.go:171","msg":"trace[420706807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8711; }","duration":"469.738539ms","start":"2026-09-08T16:04:43.599089Z","end":"2026-09-08T16:04:44.068828Z","steps":["trace[420706807] 'agreement among raft nodes before linearized reading' (duration: 469.529433ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.068878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:43.599062Z","time spent":"469.808021ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-08T16:04:44.06977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.786607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.069815Z","caller":"traceutil/trace.go:171","msg":"trace[1544307216] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8712; }","duration":"210.862409ms","start":"2026-09-08T16:04:43.858942Z","end":"2026-09-08T16:04:44.069804Z","steps":["trace[1544307216] 'agreement among raft nodes before linearized reading' (duration: 210.766106ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:44.070027Z","caller":"traceutil/trace.go:171","msg":"trace[444323613] transaction","detail":"{read_only:false; response_revision:8712; number_of_response:1; }","duration":"211.046265ms","start":"2026-09-08T16:04:43.858972Z","end":"2026-09-08T16:04:44.070018Z","steps":["trace[444323613] 'process raft request' (duration: 210.635053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.071642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.58341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T16:04:44.071682Z","caller":"traceutil/trace.go:171","msg":"trace[253274061] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8712; }","duration":"121.681623ms","start":"2026-09-08T16:04:43.949991Z","end":"2026-09-08T16:04:44.071673Z","steps":["trace[253274061] 'agreement among raft nodes before linearized reading' (duration: 121.612391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.071826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.706187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.071866Z","caller":"traceutil/trace.go:171","msg":"trace[51750500] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8712; }","duration":"144.789909ms","start":"2026-09-08T16:04:43.927066Z","end":"2026-09-08T16:04:44.071856Z","steps":["trace[51750500] 'agreement among raft nodes before linearized reading' (duration: 144.730288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.072044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.972223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.07208Z","caller":"traceutil/trace.go:171","msg":"trace[141366648] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8712; }","duration":"146.096746ms","start":"2026-09-08T16:04:43.925974Z","end":"2026-09-08T16:04:44.072071Z","steps":["trace[141366648] 'agreement among raft nodes before linearized reading' (duration: 146.040645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.072215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.374514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.072249Z","caller":"traceutil/trace.go:171","msg":"trace[1075410569] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8712; }","duration":"146.411915ms","start":"2026-09-08T16:04:43.925828Z","end":"2026-09-08T16:04:44.07224Z","steps":["trace[1075410569] 'agreement among raft nodes before linearized reading' (duration: 146.359944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.07238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.758922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.072412Z","caller":"traceutil/trace.go:171","msg":"trace[2091438645] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8712; }","duration":"168.850184ms","start":"2026-09-08T16:04:43.903554Z","end":"2026-09-08T16:04:44.072404Z","steps":["trace[2091438645] 'agreement among raft nodes before linearized reading' (duration: 168.798201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.072994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.056717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.073039Z","caller":"traceutil/trace.go:171","msg":"trace[196164344] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8712; }","duration":"170.173631ms","start":"2026-09-08T16:04:43.902856Z","end":"2026-09-08T16:04:44.073029Z","steps":["trace[196164344] 'agreement among raft nodes before linearized reading' (duration: 170.086928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.073036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.197181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.073091Z","caller":"traceutil/trace.go:171","msg":"trace[167771941] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8712; }","duration":"170.291834ms","start":"2026-09-08T16:04:43.902782Z","end":"2026-09-08T16:04:44.073074Z","steps":["trace[167771941] 'agreement among raft nodes before linearized reading' (duration: 170.202492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.073213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.79192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.073254Z","caller":"traceutil/trace.go:171","msg":"trace[436451912] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8712; }","duration":"194.876172ms","start":"2026-09-08T16:04:43.878364Z","end":"2026-09-08T16:04:44.07324Z","steps":["trace[436451912] 'agreement among raft nodes before linearized reading' (duration: 194.82179ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.073452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.082087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.073502Z","caller":"traceutil/trace.go:171","msg":"trace[1058344293] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8712; }","duration":"195.161589ms","start":"2026-09-08T16:04:43.878328Z","end":"2026-09-08T16:04:44.073489Z","steps":["trace[1058344293] 'agreement among raft nodes before linearized reading' (duration: 195.087277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.073691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.319344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.073745Z","caller":"traceutil/trace.go:171","msg":"trace[1916684667] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8712; }","duration":"195.415526ms","start":"2026-09-08T16:04:43.878316Z","end":"2026-09-08T16:04:44.073732Z","steps":["trace[1916684667] 'agreement among raft nodes before linearized reading' (duration: 195.328644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.073811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.725287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.073844Z","caller":"traceutil/trace.go:171","msg":"trace[1871947445] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8712; }","duration":"214.8269ms","start":"2026-09-08T16:04:43.859008Z","end":"2026-09-08T16:04:44.073835Z","steps":["trace[1871947445] 'agreement among raft nodes before linearized reading' (duration: 214.775909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.074011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.476364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fdft4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-08T16:04:44.074036Z","caller":"traceutil/trace.go:171","msg":"trace[2111857153] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fdft4; range_end:; response_count:1; response_revision:8712; }","duration":"207.542765ms","start":"2026-09-08T16:04:43.866487Z","end":"2026-09-08T16:04:44.07403Z","steps":["trace[2111857153] 'agreement among raft nodes before linearized reading' (duration: 207.465203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.074117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.970044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.074181Z","caller":"traceutil/trace.go:171","msg":"trace[1785305068] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8712; }","duration":"215.077256ms","start":"2026-09-08T16:04:43.859086Z","end":"2026-09-08T16:04:44.074163Z","steps":["trace[1785305068] 'agreement among raft nodes before linearized reading' (duration: 214.949582ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:54.569015Z","caller":"traceutil/trace.go:171","msg":"trace[762897253] transaction","detail":"{read_only:false; response_revision:8767; number_of_response:1; }","duration":"171.788595ms","start":"2026-09-08T16:04:54.397198Z","end":"2026-09-08T16:04:54.568986Z","steps":["trace[762897253] 'process raft request' (duration: 171.25559ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:55.222476Z","caller":"traceutil/trace.go:171","msg":"trace[1370208170] transaction","detail":"{read_only:false; response_revision:8769; number_of_response:1; }","duration":"124.433941ms","start":"2026-09-08T16:04:55.098015Z","end":"2026-09-08T16:04:55.222449Z","steps":["trace[1370208170] 'process raft request' (duration: 124.04894ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:56.276309Z","caller":"traceutil/trace.go:171","msg":"trace[1375096799] linearizableReadLoop","detail":"{readStateIndex:9095; appliedIndex:9094; }","duration":"343.023176ms","start":"2026-09-08T16:04:55.933257Z","end":"2026-09-08T16:04:56.27628Z","steps":["trace[1375096799] 'read index received' (duration: 266.420953ms)","trace[1375096799] 'applied index is now lower than readState.Index' (duration: 76.600832ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:04:56.276672Z","caller":"traceutil/trace.go:171","msg":"trace[1261681679] transaction","detail":"{read_only:false; response_revision:8772; number_of_response:1; }","duration":"343.611152ms","start":"2026-09-08T16:04:55.933047Z","end":"2026-09-08T16:04:56.276658Z","steps":["trace[1261681679] 'process raft request' (duration: 266.616929ms)","trace[1261681679] 'compare' (duration: 76.424777ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:04:56.276786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:55.933024Z","time spent":"343.686913ms","remote":"127.0.0.1:40442","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-09-08T16:04:56.277139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.843748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-08T16:04:56.277255Z","caller":"traceutil/trace.go:171","msg":"trace[1787200358] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8772; }","duration":"344.010253ms","start":"2026-09-08T16:04:55.933216Z","end":"2026-09-08T16:04:56.277227Z","steps":["trace[1787200358] 'agreement among raft nodes before linearized reading' (duration: 343.704874ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:56.277321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:55.933196Z","time spent":"344.110426ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-09-08T16:04:56.334128Z","caller":"traceutil/trace.go:171","msg":"trace[1570457239] transaction","detail":"{read_only:false; response_revision:8774; number_of_response:1; }","duration":"215.793177ms","start":"2026-09-08T16:04:56.118288Z","end":"2026-09-08T16:04:56.334081Z","steps":["trace[1570457239] 'process raft request' (duration: 215.717084ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:56.334152Z","caller":"traceutil/trace.go:171","msg":"trace[979583219] transaction","detail":"{read_only:false; response_revision:8773; number_of_response:1; }","duration":"358.265371ms","start":"2026-09-08T16:04:55.975825Z","end":"2026-09-08T16:04:56.33409Z","steps":["trace[979583219] 'process raft request' (duration: 357.999883ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:56.334267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.212697ms","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-09-08T16:04:56.334362Z","caller":"traceutil/trace.go:171","msg":"trace[1116047670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8774; }","duration":"187.389432ms","start":"2026-09-08T16:04:56.146948Z","end":"2026-09-08T16:04:56.334337Z","steps":["trace[1116047670] 'agreement among raft nodes before linearized reading' (duration: 187.133325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:56.33435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.009162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-08T16:04:56.334308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.48686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-08T16:04:56.334524Z","caller":"traceutil/trace.go:171","msg":"trace[99240952] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8774; }","duration":"175.747097ms","start":"2026-09-08T16:04:56.158754Z","end":"2026-09-08T16:04:56.334501Z","steps":["trace[99240952] 'agreement among raft nodes before linearized reading' (duration: 175.417207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:56.334393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.996783ms","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-09-08T16:04:56.334628Z","caller":"traceutil/trace.go:171","msg":"trace[400614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8774; }","duration":"176.26108ms","start":"2026-09-08T16:04:56.158343Z","end":"2026-09-08T16:04:56.334605Z","steps":["trace[400614] 'agreement among raft nodes before linearized reading' (duration: 175.924311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:56.334413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:55.975788Z","time spent":"358.484577ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T16:04:56.334421Z","caller":"traceutil/trace.go:171","msg":"trace[384643027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8774; }","duration":"172.122566ms","start":"2026-09-08T16:04:56.162278Z","end":"2026-09-08T16:04:56.3344Z","steps":["trace[384643027] 'agreement among raft nodes before linearized reading' (duration: 171.839008ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:56.334424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.235637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fdft4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-08T16:04:56.334869Z","caller":"traceutil/trace.go:171","msg":"trace[2030528246] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fdft4; range_end:; response_count:1; response_revision:8774; }","duration":"163.70317ms","start":"2026-09-08T16:04:56.171142Z","end":"2026-09-08T16:04:56.334845Z","steps":["trace[2030528246] 'agreement among raft nodes before linearized reading' (duration: 163.070863ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:56.807647Z","caller":"traceutil/trace.go:171","msg":"trace[507094691] transaction","detail":"{read_only:false; response_revision:8781; number_of_response:1; }","duration":"103.326201ms","start":"2026-09-08T16:04:56.704291Z","end":"2026-09-08T16:04:56.807618Z","steps":["trace[507094691] 'process raft request' (duration: 103.155785ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:26.883952Z","caller":"traceutil/trace.go:171","msg":"trace[1333279871] linearizableReadLoop","detail":"{readStateIndex:9319; appliedIndex:9318; }","duration":"426.800465ms","start":"2026-09-08T16:05:26.457121Z","end":"2026-09-08T16:05:26.883921Z","steps":["trace[1333279871] 'read index received' (duration: 426.629711ms)","trace[1333279871] 'applied index is now lower than readState.Index' (duration: 169.374µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:05:26.884257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.099184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-09-08T16:05:26.884304Z","caller":"traceutil/trace.go:171","msg":"trace[99261256] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8988; }","duration":"427.197287ms","start":"2026-09-08T16:05:26.457093Z","end":"2026-09-08T16:05:26.88429Z","steps":["trace[99261256] 'agreement among raft nodes before linearized reading' (duration: 426.987481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:26.884345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:26.457081Z","time spent":"427.255718ms","remote":"127.0.0.1:34252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5705,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-08T16:05:26.884463Z","caller":"traceutil/trace.go:171","msg":"trace[134631326] transaction","detail":"{read_only:false; response_revision:8988; number_of_response:1; }","duration":"430.659853ms","start":"2026-09-08T16:05:26.453774Z","end":"2026-09-08T16:05:26.884434Z","steps":["trace[134631326] 'process raft request' (duration: 429.933703ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:26.884673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:26.453751Z","time spent":"430.817367ms","remote":"127.0.0.1:40442","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-09-08T16:05:27.157107Z","caller":"traceutil/trace.go:171","msg":"trace[1742658376] linearizableReadLoop","detail":"{readStateIndex:9320; appliedIndex:9319; }","duration":"273.017165ms","start":"2026-09-08T16:05:26.884054Z","end":"2026-09-08T16:05:27.157072Z","steps":["trace[1742658376] 'read index received' (duration: 272.762348ms)","trace[1742658376] 'applied index is now lower than readState.Index' (duration: 253.147µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:27.157402Z","caller":"traceutil/trace.go:171","msg":"trace[333623541] transaction","detail":"{read_only:false; response_revision:8989; number_of_response:1; }","duration":"697.032441ms","start":"2026-09-08T16:05:26.460335Z","end":"2026-09-08T16:05:27.157368Z","steps":["trace[333623541] 'process raft request' (duration: 696.473555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:27.157467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.259302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-08T16:05:27.157473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.153175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:05:27.157534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.068278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fdft4\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-09-08T16:05:27.157522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.562879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T16:05:27.157593Z","caller":"traceutil/trace.go:171","msg":"trace[692191741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8989; }","duration":"471.369031ms","start":"2026-09-08T16:05:26.686197Z","end":"2026-09-08T16:05:27.157567Z","steps":["trace[692191741] 'agreement among raft nodes before linearized reading' (duration: 471.112454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:27.157581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:26.46031Z","time spent":"697.184655ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T16:05:27.157615Z","caller":"traceutil/trace.go:171","msg":"trace[1744157066] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fdft4; range_end:; response_count:1; response_revision:8989; }","duration":"596.198541ms","start":"2026-09-08T16:05:26.561395Z","end":"2026-09-08T16:05:27.157594Z","steps":["trace[1744157066] 'agreement among raft nodes before linearized reading' (duration: 595.973145ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:27.157644Z","caller":"traceutil/trace.go:171","msg":"trace[1893437136] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8989; }","duration":"519.792405ms","start":"2026-09-08T16:05:26.637822Z","end":"2026-09-08T16:05:27.157614Z","steps":["trace[1893437136] 'agreement among raft nodes before linearized reading' (duration: 519.413724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:27.157663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:26.686162Z","time spent":"471.488555ms","remote":"127.0.0.1:40158","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-09-08T16:05:27.157571Z","caller":"traceutil/trace.go:171","msg":"trace[1330554165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8989; }","duration":"518.400647ms","start":"2026-09-08T16:05:26.639146Z","end":"2026-09-08T16:05:27.157547Z","steps":["trace[1330554165] 'agreement among raft nodes before linearized reading' (duration: 518.16639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:27.157677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:26.561366Z","time spent":"596.294184ms","remote":"127.0.0.1:40336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-fdft4\" "} {"level":"warn","ts":"2026-09-08T16:05:27.157709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:26.637788Z","time spent":"519.910428ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-08T16:05:27.157713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:26.639137Z","time spent":"518.568772ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-08T16:05:27.157852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.759734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-09-08T16:05:27.157659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.519222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-08T16:05:27.158014Z","caller":"traceutil/trace.go:171","msg":"trace[104081841] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8989; }","duration":"510.933068ms","start":"2026-09-08T16:05:26.647028Z","end":"2026-09-08T16:05:27.157961Z","steps":["trace[104081841] 'agreement among raft nodes before linearized reading' (duration: 510.370882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:27.158094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:26.647018Z","time spent":"511.061521ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-08T16:05:27.158033Z","caller":"traceutil/trace.go:171","msg":"trace[1552424367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8989; }","duration":"512.875832ms","start":"2026-09-08T16:05:26.645105Z","end":"2026-09-08T16:05:27.15798Z","steps":["trace[1552424367] 'agreement among raft nodes before linearized reading' (duration: 512.005187ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:27.15823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:26.645093Z","time spent":"513.115479ms","remote":"127.0.0.1:40442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-08T16:05:27.158401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.273477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-798c7fcd56-ltkbz.18d3640b457a51d0\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-09-08T16:05:27.158481Z","caller":"traceutil/trace.go:171","msg":"trace[1837715341] range","detail":"{range_begin:/registry/events/openstack/barbican-api-798c7fcd56-ltkbz.18d3640b457a51d0; range_end:; response_count:1; response_revision:8989; }","duration":"585.40185ms","start":"2026-09-08T16:05:26.573063Z","end":"2026-09-08T16:05:27.158465Z","steps":["trace[1837715341] 'agreement among raft nodes before linearized reading' (duration: 584.262168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:27.158542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:26.573032Z","time spent":"585.494933ms","remote":"127.0.0.1:40246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1156,"request content":"key:\"/registry/events/openstack/barbican-api-798c7fcd56-ltkbz.18d3640b457a51d0\" "} {"level":"info","ts":"2026-09-08T16:05:27.179204Z","caller":"traceutil/trace.go:171","msg":"trace[1139744791] transaction","detail":"{read_only:false; response_revision:8990; number_of_response:1; }","duration":"218.060218ms","start":"2026-09-08T16:05:26.961113Z","end":"2026-09-08T16:05:27.179173Z","steps":["trace[1139744791] 'process raft request' (duration: 217.667057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:27.179227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.491912ms","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-09-08T16:05:27.179471Z","caller":"traceutil/trace.go:171","msg":"trace[211239239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8990; }","duration":"197.79713ms","start":"2026-09-08T16:05:26.98165Z","end":"2026-09-08T16:05:27.179447Z","steps":["trace[211239239] 'agreement among raft nodes before linearized reading' (duration: 197.363728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:27.179204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.736668ms","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-09-08T16:05:27.179781Z","caller":"traceutil/trace.go:171","msg":"trace[1363762415] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8990; }","duration":"223.367875ms","start":"2026-09-08T16:05:26.956374Z","end":"2026-09-08T16:05:27.179741Z","steps":["trace[1363762415] 'agreement among raft nodes before linearized reading' (duration: 222.718537ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:27.265024Z","caller":"traceutil/trace.go:171","msg":"trace[1127697754] transaction","detail":"{read_only:false; response_revision:8991; number_of_response:1; }","duration":"104.289186ms","start":"2026-09-08T16:05:27.160671Z","end":"2026-09-08T16:05:27.26496Z","steps":["trace[1127697754] 'process raft request' (duration: 86.727665ms)","trace[1127697754] 'compare' (duration: 17.300334ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:27.265518Z","caller":"traceutil/trace.go:171","msg":"trace[487094657] transaction","detail":"{read_only:false; response_revision:8993; number_of_response:1; }","duration":"102.663471ms","start":"2026-09-08T16:05:27.162832Z","end":"2026-09-08T16:05:27.265496Z","steps":["trace[487094657] 'process raft request' (duration: 102.417454ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:27.265503Z","caller":"traceutil/trace.go:171","msg":"trace[191381331] transaction","detail":"{read_only:false; response_revision:8992; number_of_response:1; }","duration":"102.669052ms","start":"2026-09-08T16:05:27.162789Z","end":"2026-09-08T16:05:27.265458Z","steps":["trace[191381331] 'process raft request' (duration: 102.081246ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:27.265697Z","caller":"traceutil/trace.go:171","msg":"trace[2094163452] transaction","detail":"{read_only:false; response_revision:8994; number_of_response:1; }","duration":"102.754034ms","start":"2026-09-08T16:05:27.162931Z","end":"2026-09-08T16:05:27.265685Z","steps":["trace[2094163452] 'process raft request' (duration: 102.499797ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:27.265793Z","caller":"traceutil/trace.go:171","msg":"trace[2047281824] transaction","detail":"{read_only:false; response_revision:8995; number_of_response:1; }","duration":"102.303911ms","start":"2026-09-08T16:05:27.16348Z","end":"2026-09-08T16:05:27.265784Z","steps":["trace[2047281824] 'process raft request' (duration: 102.152897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:27.26614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.681003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:05:27.266179Z","caller":"traceutil/trace.go:171","msg":"trace[1592528571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8996; }","duration":"106.834747ms","start":"2026-09-08T16:05:27.159334Z","end":"2026-09-08T16:05:27.266169Z","steps":["trace[1592528571] 'agreement among raft nodes before linearized reading' (duration: 106.734515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:27.267595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.388431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228533"} {"level":"info","ts":"2026-09-08T16:05:27.267671Z","caller":"traceutil/trace.go:171","msg":"trace[10024222] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8996; }","duration":"103.489504ms","start":"2026-09-08T16:05:27.164163Z","end":"2026-09-08T16:05:27.267653Z","steps":["trace[10024222] 'agreement among raft nodes before linearized reading' (duration: 101.90443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:33.800806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.987227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T16:05:33.800922Z","caller":"traceutil/trace.go:171","msg":"trace[947736015] linearizableReadLoop","detail":"{readStateIndex:9361; appliedIndex:9360; }","duration":"229.819477ms","start":"2026-09-08T16:05:33.571085Z","end":"2026-09-08T16:05:33.800904Z","steps":["trace[947736015] 'read index received' (duration: 100.685816ms)","trace[947736015] 'applied index is now lower than readState.Index' (duration: 129.132111ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:33.801066Z","caller":"traceutil/trace.go:171","msg":"trace[1302905086] transaction","detail":"{read_only:false; response_revision:9029; number_of_response:1; }","duration":"274.401573ms","start":"2026-09-08T16:05:33.526646Z","end":"2026-09-08T16:05:33.801047Z","steps":["trace[1302905086] 'process raft request' (duration: 145.083587ms)","trace[1302905086] 'compare' (duration: 128.879324ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:05:33.801241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.097344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T16:05:33.80139Z","caller":"traceutil/trace.go:171","msg":"trace[1394600370] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:9029; }","duration":"230.33136ms","start":"2026-09-08T16:05:33.571022Z","end":"2026-09-08T16:05:33.801354Z","steps":["trace[1394600370] 'agreement among raft nodes before linearized reading' (duration: 230.023692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:33.801495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.575075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T16:05:33.801538Z","caller":"traceutil/trace.go:171","msg":"trace[1273495968] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:9029; }","duration":"207.666517ms","start":"2026-09-08T16:05:33.593861Z","end":"2026-09-08T16:05:33.801527Z","steps":["trace[1273495968] 'agreement among raft nodes before linearized reading' (duration: 207.477052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:34.1147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.047323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:05:34.114805Z","caller":"traceutil/trace.go:171","msg":"trace[1916148445] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:9029; }","duration":"147.223297ms","start":"2026-09-08T16:05:33.967555Z","end":"2026-09-08T16:05:34.114778Z","steps":["trace[1916148445] 'count revisions from in-memory index tree' (duration: 146.930369ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:34.115056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.148246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-09-08T16:05:34.115114Z","caller":"traceutil/trace.go:171","msg":"trace[1978150217] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:9029; }","duration":"117.2803ms","start":"2026-09-08T16:05:33.997819Z","end":"2026-09-08T16:05:34.1151Z","steps":["trace[1978150217] 'range keys from in-memory index tree' (duration: 116.90859ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:41.288755Z","caller":"traceutil/trace.go:171","msg":"trace[727781356] linearizableReadLoop","detail":"{readStateIndex:9438; appliedIndex:9437; }","duration":"113.353801ms","start":"2026-09-08T16:05:41.175369Z","end":"2026-09-08T16:05:41.288723Z","steps":["trace[727781356] 'read index received' (duration: 113.019592ms)","trace[727781356] 'applied index is now lower than readState.Index' (duration: 333.049µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:41.289391Z","caller":"traceutil/trace.go:171","msg":"trace[1288229447] transaction","detail":"{read_only:false; response_revision:9102; number_of_response:1; }","duration":"117.425084ms","start":"2026-09-08T16:05:41.17195Z","end":"2026-09-08T16:05:41.289375Z","steps":["trace[1288229447] 'process raft request' (duration: 116.56098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:41.289786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.386189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-6j7zh\" ","response":"range_response_count:1 size:2802"} {"level":"info","ts":"2026-09-08T16:05:41.289834Z","caller":"traceutil/trace.go:171","msg":"trace[1141568701] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-6j7zh; range_end:; response_count:1; response_revision:9102; }","duration":"114.506032ms","start":"2026-09-08T16:05:41.175315Z","end":"2026-09-08T16:05:41.289821Z","steps":["trace[1141568701] 'agreement among raft nodes before linearized reading' (duration: 114.316187ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:41.436416Z","caller":"traceutil/trace.go:171","msg":"trace[1780060847] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9104; }","duration":"143.349099ms","start":"2026-09-08T16:05:41.293044Z","end":"2026-09-08T16:05:41.436393Z","steps":["trace[1780060847] 'process raft request' (duration: 143.252256ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:41.436768Z","caller":"traceutil/trace.go:171","msg":"trace[1201246332] transaction","detail":"{read_only:false; response_revision:9103; number_of_response:1; }","duration":"219.56657ms","start":"2026-09-08T16:05:41.217187Z","end":"2026-09-08T16:05:41.436753Z","steps":["trace[1201246332] 'process raft request' (duration: 218.947892ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:41.436906Z","caller":"traceutil/trace.go:171","msg":"trace[1168684733] linearizableReadLoop","detail":"{readStateIndex:9440; appliedIndex:9438; }","duration":"147.993468ms","start":"2026-09-08T16:05:41.2889Z","end":"2026-09-08T16:05:41.436893Z","steps":["trace[1168684733] 'read index received' (duration: 84.304757ms)","trace[1168684733] 'applied index is now lower than readState.Index' (duration: 63.666471ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:05:41.43728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.251289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3173"} {"level":"info","ts":"2026-09-08T16:05:41.437324Z","caller":"traceutil/trace.go:171","msg":"trace[1094069142] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:9104; }","duration":"220.341092ms","start":"2026-09-08T16:05:41.216972Z","end":"2026-09-08T16:05:41.437313Z","steps":["trace[1094069142] 'agreement among raft nodes before linearized reading' (duration: 220.180757ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:41.437962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.354419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6j7zh\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-09-08T16:05:41.438046Z","caller":"traceutil/trace.go:171","msg":"trace[1036188335] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6j7zh; range_end:; response_count:1; response_revision:9104; }","duration":"143.470971ms","start":"2026-09-08T16:05:41.294554Z","end":"2026-09-08T16:05:41.438025Z","steps":["trace[1036188335] 'agreement among raft nodes before linearized reading' (duration: 143.143742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:41.438428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.236178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-09-08T16:05:41.438577Z","caller":"traceutil/trace.go:171","msg":"trace[1811809228] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:9104; }","duration":"225.392662ms","start":"2026-09-08T16:05:41.213101Z","end":"2026-09-08T16:05:41.438493Z","steps":["trace[1811809228] 'agreement among raft nodes before linearized reading' (duration: 224.152918ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:41.556605Z","caller":"traceutil/trace.go:171","msg":"trace[1346903779] linearizableReadLoop","detail":"{readStateIndex:9445; appliedIndex:9441; }","duration":"109.595775ms","start":"2026-09-08T16:05:41.446987Z","end":"2026-09-08T16:05:41.556583Z","steps":["trace[1346903779] 'read index received' (duration: 18.860408ms)","trace[1346903779] 'applied index is now lower than readState.Index' (duration: 90.734377ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:41.55693Z","caller":"traceutil/trace.go:171","msg":"trace[1777984450] transaction","detail":"{read_only:false; response_revision:9106; number_of_response:1; }","duration":"111.601201ms","start":"2026-09-08T16:05:41.445317Z","end":"2026-09-08T16:05:41.556918Z","steps":["trace[1777984450] 'process raft request' (duration: 110.86687ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:41.557318Z","caller":"traceutil/trace.go:171","msg":"trace[1763651704] transaction","detail":"{read_only:false; response_revision:9107; number_of_response:1; }","duration":"111.781436ms","start":"2026-09-08T16:05:41.445525Z","end":"2026-09-08T16:05:41.557306Z","steps":["trace[1763651704] 'process raft request' (duration: 110.853929ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:41.557502Z","caller":"traceutil/trace.go:171","msg":"trace[554956081] transaction","detail":"{read_only:false; response_revision:9108; number_of_response:1; }","duration":"111.874859ms","start":"2026-09-08T16:05:41.445612Z","end":"2026-09-08T16:05:41.557487Z","steps":["trace[554956081] 'process raft request' (duration: 110.817538ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:41.557632Z","caller":"traceutil/trace.go:171","msg":"trace[1059413869] transaction","detail":"{read_only:false; response_revision:9109; number_of_response:1; }","duration":"110.621064ms","start":"2026-09-08T16:05:41.447003Z","end":"2026-09-08T16:05:41.557624Z","steps":["trace[1059413869] 'process raft request' (duration: 109.464241ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:41.557937Z","caller":"traceutil/trace.go:171","msg":"trace[1082385435] transaction","detail":"{read_only:false; response_revision:9110; number_of_response:1; }","duration":"108.508985ms","start":"2026-09-08T16:05:41.449414Z","end":"2026-09-08T16:05:41.557923Z","steps":["trace[1082385435] 'process raft request' (duration: 107.128925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:41.558389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.380065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228443"} {"level":"info","ts":"2026-09-08T16:05:41.55843Z","caller":"traceutil/trace.go:171","msg":"trace[897411743] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:9110; }","duration":"111.438326ms","start":"2026-09-08T16:05:41.446983Z","end":"2026-09-08T16:05:41.558421Z","steps":["trace[897411743] 'agreement among raft nodes before linearized reading' (duration: 111.067546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:41.574488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.637891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ceph.18d36415a840e461\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-09-08T16:05:41.574564Z","caller":"traceutil/trace.go:171","msg":"trace[1542675000] range","detail":"{range_begin:/registry/events/openstack/ceph.18d36415a840e461; range_end:; response_count:1; response_revision:9111; }","duration":"126.751095ms","start":"2026-09-08T16:05:41.447796Z","end":"2026-09-08T16:05:41.574547Z","steps":["trace[1542675000] 'agreement among raft nodes before linearized reading' (duration: 126.57573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:41.574959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.561519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6j7zh\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-09-08T16:05:41.575008Z","caller":"traceutil/trace.go:171","msg":"trace[1576939167] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6j7zh; range_end:; response_count:1; response_revision:9111; }","duration":"127.240208ms","start":"2026-09-08T16:05:41.447756Z","end":"2026-09-08T16:05:41.574996Z","steps":["trace[1576939167] 'agreement among raft nodes before linearized reading' (duration: 126.418465ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:41.943417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.412267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T16:05:41.943474Z","caller":"traceutil/trace.go:171","msg":"trace[1586412802] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:9114; }","duration":"134.551173ms","start":"2026-09-08T16:05:41.808909Z","end":"2026-09-08T16:05:41.94346Z","steps":["trace[1586412802] 'count revisions from in-memory index tree' (duration: 134.204102ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:41.968798Z","caller":"traceutil/trace.go:171","msg":"trace[1494027459] transaction","detail":"{read_only:false; response_revision:9115; number_of_response:1; }","duration":"127.924927ms","start":"2026-09-08T16:05:41.840853Z","end":"2026-09-08T16:05:41.968778Z","steps":["trace[1494027459] 'process raft request' (duration: 127.759802ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:42.251298Z","caller":"traceutil/trace.go:171","msg":"trace[869072627] linearizableReadLoop","detail":"{readStateIndex:9453; appliedIndex:9452; }","duration":"107.615619ms","start":"2026-09-08T16:05:42.143483Z","end":"2026-09-08T16:05:42.251099Z","steps":["trace[869072627] 'read index received' (duration: 107.27447ms)","trace[869072627] 'applied index is now lower than readState.Index' (duration: 340.119µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:05:42.251976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.474054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-08T16:05:42.252071Z","caller":"traceutil/trace.go:171","msg":"trace[1297186693] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9116; }","duration":"108.611868ms","start":"2026-09-08T16:05:42.143437Z","end":"2026-09-08T16:05:42.252049Z","steps":["trace[1297186693] 'agreement among raft nodes before linearized reading' (duration: 108.226957ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:42.252601Z","caller":"traceutil/trace.go:171","msg":"trace[1013390147] transaction","detail":"{read_only:false; response_revision:9116; number_of_response:1; }","duration":"111.425936ms","start":"2026-09-08T16:05:42.141159Z","end":"2026-09-08T16:05:42.252585Z","steps":["trace[1013390147] 'process raft request' (duration: 109.702058ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:53.493634Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002555.wal"} {"level":"info","ts":"2026-09-08T16:06:38.667283Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db28a37eabf8270","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T16:06:38.679501Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-08T16:06:38.679601Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-08T16:07:04.216326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.233489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T16:07:04.216449Z","caller":"traceutil/trace.go:171","msg":"trace[319773876] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:9861; }","duration":"308.405834ms","start":"2026-09-08T16:07:03.90802Z","end":"2026-09-08T16:07:04.216425Z","steps":["trace[319773876] 'count revisions from in-memory index tree' (duration: 308.156608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:07:04.216527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:07:03.908007Z","time spent":"308.473737ms","remote":"127.0.0.1:40302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":8,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-09-08T16:07:04.217275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.23196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T16:07:04.217499Z","caller":"traceutil/trace.go:171","msg":"trace[2007399013] transaction","detail":"{read_only:false; response_revision:9862; number_of_response:1; }","duration":"274.320311ms","start":"2026-09-08T16:07:03.943131Z","end":"2026-09-08T16:07:04.217451Z","steps":["trace[2007399013] 'process raft request' (duration: 128.786482ms)","trace[2007399013] 'compare' (duration: 144.529381ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:07:04.217571Z","caller":"traceutil/trace.go:171","msg":"trace[1425934986] linearizableReadLoop","detail":"{readStateIndex:10219; appliedIndex:10218; }","duration":"263.889149ms","start":"2026-09-08T16:07:03.953479Z","end":"2026-09-08T16:07:04.217368Z","steps":["trace[1425934986] 'read index received' (duration: 118.305399ms)","trace[1425934986] 'applied index is now lower than readState.Index' (duration: 145.58181ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:07:04.217893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.351843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-08T16:07:04.218036Z","caller":"traceutil/trace.go:171","msg":"trace[939747343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9862; }","duration":"264.58731ms","start":"2026-09-08T16:07:03.953417Z","end":"2026-09-08T16:07:04.218005Z","steps":["trace[939747343] 'agreement among raft nodes before linearized reading' (duration: 264.21871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:07:04.218255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.033254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-08T16:07:04.218292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.560173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-09-08T16:07:04.218307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.06859ms","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-09-08T16:07:04.218387Z","caller":"traceutil/trace.go:171","msg":"trace[1612769371] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9862; }","duration":"126.247131ms","start":"2026-09-08T16:07:04.092115Z","end":"2026-09-08T16:07:04.218362Z","steps":["trace[1612769371] 'agreement among raft nodes before linearized reading' (duration: 125.86916ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:07:04.218396Z","caller":"traceutil/trace.go:171","msg":"trace[1012514351] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9862; }","duration":"134.732418ms","start":"2026-09-08T16:07:04.083641Z","end":"2026-09-08T16:07:04.218373Z","steps":["trace[1012514351] 'agreement among raft nodes before linearized reading' (duration: 134.382818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:07:04.218277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.849376ms","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-09-08T16:07:04.218587Z","caller":"traceutil/trace.go:171","msg":"trace[1218385413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9862; }","duration":"191.197976ms","start":"2026-09-08T16:07:04.027369Z","end":"2026-09-08T16:07:04.218567Z","steps":["trace[1218385413] 'agreement among raft nodes before linearized reading' (duration: 190.673552ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:07:04.218448Z","caller":"traceutil/trace.go:171","msg":"trace[1043510087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9862; }","duration":"202.281537ms","start":"2026-09-08T16:07:04.016127Z","end":"2026-09-08T16:07:04.218409Z","steps":["trace[1043510087] 'agreement among raft nodes before linearized reading' (duration: 201.860515ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:07:04.429642Z","caller":"traceutil/trace.go:171","msg":"trace[1265801140] transaction","detail":"{read_only:false; response_revision:9863; number_of_response:1; }","duration":"206.051941ms","start":"2026-09-08T16:07:04.223552Z","end":"2026-09-08T16:07:04.429604Z","steps":["trace[1265801140] 'process raft request' (duration: 109.248615ms)","trace[1265801140] 'compare' (duration: 96.655832ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:07:04.490703Z","caller":"traceutil/trace.go:171","msg":"trace[810170218] transaction","detail":"{read_only:false; response_revision:9868; number_of_response:1; }","duration":"264.96845ms","start":"2026-09-08T16:07:04.225701Z","end":"2026-09-08T16:07:04.49067Z","steps":["trace[810170218] 'process raft request' (duration: 264.919369ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:07:04.491069Z","caller":"traceutil/trace.go:171","msg":"trace[1860886243] transaction","detail":"{read_only:false; response_revision:9866; number_of_response:1; }","duration":"266.896263ms","start":"2026-09-08T16:07:04.224131Z","end":"2026-09-08T16:07:04.491027Z","steps":["trace[1860886243] 'process raft request' (duration: 266.40728ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:07:04.491064Z","caller":"traceutil/trace.go:171","msg":"trace[715767236] transaction","detail":"{read_only:false; response_revision:9865; number_of_response:1; }","duration":"266.894564ms","start":"2026-09-08T16:07:04.224117Z","end":"2026-09-08T16:07:04.491012Z","steps":["trace[715767236] 'process raft request' (duration: 266.367569ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:07:04.491213Z","caller":"traceutil/trace.go:171","msg":"trace[1647778606] transaction","detail":"{read_only:false; response_revision:9867; number_of_response:1; }","duration":"267.041148ms","start":"2026-09-08T16:07:04.224154Z","end":"2026-09-08T16:07:04.491195Z","steps":["trace[1647778606] 'process raft request' (duration: 266.426621ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:07:04.491352Z","caller":"traceutil/trace.go:171","msg":"trace[1894327393] transaction","detail":"{read_only:false; response_revision:9864; number_of_response:1; }","duration":"267.322216ms","start":"2026-09-08T16:07:04.224011Z","end":"2026-09-08T16:07:04.491334Z","steps":["trace[1894327393] 'process raft request' (duration: 266.323358ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:07:04.507948Z","caller":"traceutil/trace.go:171","msg":"trace[1453744020] linearizableReadLoop","detail":"{readStateIndex:10226; appliedIndex:10225; }","duration":"111.261751ms","start":"2026-09-08T16:07:04.396656Z","end":"2026-09-08T16:07:04.507917Z","steps":["trace[1453744020] 'read index received' (duration: 94.322968ms)","trace[1453744020] 'applied index is now lower than readState.Index' (duration: 16.937433ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:07:04.50807Z","caller":"traceutil/trace.go:171","msg":"trace[1272996447] transaction","detail":"{read_only:false; response_revision:9869; number_of_response:1; }","duration":"141.373563ms","start":"2026-09-08T16:07:04.366655Z","end":"2026-09-08T16:07:04.508028Z","steps":["trace[1272996447] 'process raft request' (duration: 141.038914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:07:04.508267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.598191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-08T16:07:04.50836Z","caller":"traceutil/trace.go:171","msg":"trace[1025495516] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9869; }","duration":"111.757736ms","start":"2026-09-08T16:07:04.396582Z","end":"2026-09-08T16:07:04.508339Z","steps":["trace[1025495516] 'agreement among raft nodes before linearized reading' (duration: 111.52966ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:07:40.783227Z","caller":"traceutil/trace.go:171","msg":"trace[2063655601] linearizableReadLoop","detail":"{readStateIndex:10530; appliedIndex:10529; }","duration":"104.788908ms","start":"2026-09-08T16:07:40.678407Z","end":"2026-09-08T16:07:40.783196Z","steps":["trace[2063655601] 'read index received' (duration: 75.125747ms)","trace[2063655601] 'applied index is now lower than readState.Index' (duration: 29.661481ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:07:40.783441Z","caller":"traceutil/trace.go:171","msg":"trace[406632472] transaction","detail":"{read_only:false; response_revision:10166; number_of_response:1; }","duration":"106.473826ms","start":"2026-09-08T16:07:40.676916Z","end":"2026-09-08T16:07:40.78339Z","steps":["trace[406632472] 'process raft request' (duration: 76.696811ms)","trace[406632472] 'compare' (duration: 29.437005ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:07:40.783563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.129888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-08T16:07:40.783613Z","caller":"traceutil/trace.go:171","msg":"trace[457246984] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10166; }","duration":"105.228591ms","start":"2026-09-08T16:07:40.678368Z","end":"2026-09-08T16:07:40.783597Z","steps":["trace[457246984] 'agreement among raft nodes before linearized reading' (duration: 105.007084ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:08:02.332485Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7898} {"level":"info","ts":"2026-09-08T16:08:02.430529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7898,"took":"94.930443ms","hash":1976967479,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":26271744,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-08T16:08:02.430641Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1976967479,"revision":7898,"compact-revision":5278} {"level":"info","ts":"2026-09-08T16:10:48.110867Z","caller":"traceutil/trace.go:171","msg":"trace[422331017] transaction","detail":"{read_only:false; response_revision:11767; number_of_response:1; }","duration":"116.024314ms","start":"2026-09-08T16:10:47.994782Z","end":"2026-09-08T16:10:48.110806Z","steps":["trace[422331017] 'process raft request' (duration: 115.822218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:10:48.333738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.80073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T16:10:48.334036Z","caller":"traceutil/trace.go:171","msg":"trace[1517611762] transaction","detail":"{read_only:false; response_revision:11770; number_of_response:1; }","duration":"217.348155ms","start":"2026-09-08T16:10:48.116667Z","end":"2026-09-08T16:10:48.334016Z","steps":["trace[1517611762] 'process raft request' (duration: 217.20539ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:10:48.334048Z","caller":"traceutil/trace.go:171","msg":"trace[1125325881] transaction","detail":"{read_only:false; response_revision:11769; number_of_response:1; }","duration":"217.430287ms","start":"2026-09-08T16:10:48.116559Z","end":"2026-09-08T16:10:48.333989Z","steps":["trace[1125325881] 'process raft request' (duration: 108.275587ms)","trace[1125325881] 'compare' (duration: 108.511162ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:10:48.334229Z","caller":"traceutil/trace.go:171","msg":"trace[679898284] transaction","detail":"{read_only:false; response_revision:11771; number_of_response:1; }","duration":"217.200081ms","start":"2026-09-08T16:10:48.116996Z","end":"2026-09-08T16:10:48.334196Z","steps":["trace[679898284] 'process raft request' (duration: 216.979184ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:13:02.341682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10344} {"level":"info","ts":"2026-09-08T16:13:02.450052Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10344,"took":"104.632213ms","hash":1934719877,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":27566080,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-08T16:13:02.450171Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1934719877,"revision":10344,"compact-revision":7898} {"level":"info","ts":"2026-09-08T16:15:05.400021Z","caller":"traceutil/trace.go:171","msg":"trace[1029760778] transaction","detail":"{read_only:false; response_revision:13447; number_of_response:1; }","duration":"109.908414ms","start":"2026-09-08T16:15:05.290084Z","end":"2026-09-08T16:15:05.399993Z","steps":["trace[1029760778] 'process raft request' (duration: 109.347248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:16:42.859764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.455147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:16:42.859907Z","caller":"traceutil/trace.go:171","msg":"trace[944198803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14183; }","duration":"173.635283ms","start":"2026-09-08T16:16:42.686245Z","end":"2026-09-08T16:16:42.859881Z","steps":["trace[944198803] 'range keys from in-memory index tree' (duration: 173.391706ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:17:27.402187Z","caller":"traceutil/trace.go:171","msg":"trace[10777551] linearizableReadLoop","detail":"{readStateIndex:14943; appliedIndex:14942; }","duration":"117.053605ms","start":"2026-09-08T16:17:27.285098Z","end":"2026-09-08T16:17:27.402151Z","steps":["trace[10777551] 'read index received' (duration: 47.091542ms)","trace[10777551] 'applied index is now lower than readState.Index' (duration: 69.960753ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:17:27.40236Z","caller":"traceutil/trace.go:171","msg":"trace[1758513095] transaction","detail":"{read_only:false; response_revision:14435; number_of_response:1; }","duration":"125.965084ms","start":"2026-09-08T16:17:27.276309Z","end":"2026-09-08T16:17:27.402274Z","steps":["trace[1758513095] 'process raft request' (duration: 55.897308ms)","trace[1758513095] 'compare' (duration: 69.778118ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:17:27.40245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.334423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-r7xhz\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-09-08T16:17:27.402529Z","caller":"traceutil/trace.go:171","msg":"trace[524523651] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-r7xhz; range_end:; response_count:1; response_revision:14435; }","duration":"117.475137ms","start":"2026-09-08T16:17:27.285036Z","end":"2026-09-08T16:17:27.402511Z","steps":["trace[524523651] 'agreement among raft nodes before linearized reading' (duration: 117.24763ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:17:39.770183Z","caller":"traceutil/trace.go:171","msg":"trace[598705377] linearizableReadLoop","detail":"{readStateIndex:15123; appliedIndex:15121; }","duration":"268.646829ms","start":"2026-09-08T16:17:39.501485Z","end":"2026-09-08T16:17:39.770132Z","steps":["trace[598705377] 'read index received' (duration: 38.146671ms)","trace[598705377] 'applied index is now lower than readState.Index' (duration: 230.498358ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:17:39.770263Z","caller":"traceutil/trace.go:171","msg":"trace[452414498] transaction","detail":"{read_only:false; response_revision:14611; number_of_response:1; }","duration":"280.979814ms","start":"2026-09-08T16:17:39.489227Z","end":"2026-09-08T16:17:39.770207Z","steps":["trace[452414498] 'process raft request' (duration: 248.004939ms)","trace[452414498] 'compare' (duration: 32.527963ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:17:39.770453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.955527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-6c6687ddd6-vx4jm\" ","response":"range_response_count:1 size:7041"} {"level":"info","ts":"2026-09-08T16:17:39.770549Z","caller":"traceutil/trace.go:171","msg":"trace[65748767] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-6c6687ddd6-vx4jm; range_end:; response_count:1; response_revision:14611; }","duration":"269.111102ms","start":"2026-09-08T16:17:39.501415Z","end":"2026-09-08T16:17:39.770526Z","steps":["trace[65748767] 'agreement among raft nodes before linearized reading' (duration: 268.858935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:39.770813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.901773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-kq22f.18d364c13cdadcdd\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-09-08T16:17:39.770907Z","caller":"traceutil/trace.go:171","msg":"trace[1608648856] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-kq22f.18d364c13cdadcdd; range_end:; response_count:1; response_revision:14611; }","duration":"131.044447ms","start":"2026-09-08T16:17:39.639844Z","end":"2026-09-08T16:17:39.770888Z","steps":["trace[1608648856] 'agreement among raft nodes before linearized reading' (duration: 130.496342ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:18:02.351661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12702} {"level":"info","ts":"2026-09-08T16:18:02.446338Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12702,"took":"91.22139ms","hash":2770253941,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":29618176,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T16:18:02.446424Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2770253941,"revision":12702,"compact-revision":10344} {"level":"info","ts":"2026-09-08T16:19:23.3957Z","caller":"traceutil/trace.go:171","msg":"trace[1134238542] transaction","detail":"{read_only:false; response_revision:15715; number_of_response:1; }","duration":"112.526478ms","start":"2026-09-08T16:19:23.283147Z","end":"2026-09-08T16:19:23.395674Z","steps":["trace[1134238542] 'process raft request' (duration: 112.365833ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:19:23.702601Z","caller":"traceutil/trace.go:171","msg":"trace[1856801883] linearizableReadLoop","detail":"{readStateIndex:16262; appliedIndex:16260; }","duration":"192.784411ms","start":"2026-09-08T16:19:23.509786Z","end":"2026-09-08T16:19:23.702571Z","steps":["trace[1856801883] 'read index received' (duration: 183.550731ms)","trace[1856801883] 'applied index is now lower than readState.Index' (duration: 9.23266ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:19:23.702873Z","caller":"traceutil/trace.go:171","msg":"trace[1209521716] transaction","detail":"{read_only:false; response_revision:15716; number_of_response:1; }","duration":"266.071957ms","start":"2026-09-08T16:19:23.436535Z","end":"2026-09-08T16:19:23.702607Z","steps":["trace[1209521716] 'process raft request' (duration: 256.782146ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:19:23.702993Z","caller":"traceutil/trace.go:171","msg":"trace[1673179730] transaction","detail":"{read_only:false; response_revision:15717; number_of_response:1; }","duration":"262.750904ms","start":"2026-09-08T16:19:23.440227Z","end":"2026-09-08T16:19:23.702978Z","steps":["trace[1673179730] 'process raft request' (duration: 262.256259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:19:23.70342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.614303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-gjkc7\" ","response":"range_response_count:1 size:9798"} {"level":"warn","ts":"2026-09-08T16:19:23.703465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.357638ms","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-09-08T16:19:23.703529Z","caller":"traceutil/trace.go:171","msg":"trace[1635725238] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-gjkc7; range_end:; response_count:1; response_revision:15718; }","duration":"193.782538ms","start":"2026-09-08T16:19:23.509721Z","end":"2026-09-08T16:19:23.703504Z","steps":["trace[1635725238] 'agreement among raft nodes before linearized reading' (duration: 193.398587ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:19:23.70356Z","caller":"traceutil/trace.go:171","msg":"trace[1227797734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15718; }","duration":"125.494411ms","start":"2026-09-08T16:19:23.578035Z","end":"2026-09-08T16:19:23.70353Z","steps":["trace[1227797734] 'agreement among raft nodes before linearized reading' (duration: 125.248355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:19:23.703597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.245383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-08T16:19:23.703654Z","caller":"traceutil/trace.go:171","msg":"trace[1297933232] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15718; }","duration":"100.319955ms","start":"2026-09-08T16:19:23.603314Z","end":"2026-09-08T16:19:23.703634Z","steps":["trace[1297933232] 'agreement among raft nodes before linearized reading' (duration: 100.179901ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:19:23.703638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.11618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-08T16:19:23.703699Z","caller":"traceutil/trace.go:171","msg":"trace[1209934611] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15718; }","duration":"100.234243ms","start":"2026-09-08T16:19:23.603449Z","end":"2026-09-08T16:19:23.703683Z","steps":["trace[1209934611] 'agreement among raft nodes before linearized reading' (duration: 100.100379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:19:23.703662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.444579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-08T16:19:23.703786Z","caller":"traceutil/trace.go:171","msg":"trace[2104789335] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15718; }","duration":"100.584083ms","start":"2026-09-08T16:19:23.603169Z","end":"2026-09-08T16:19:23.703753Z","steps":["trace[2104789335] 'agreement among raft nodes before linearized reading' (duration: 100.320965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:19:23.703871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.85971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T16:19:23.703949Z","caller":"traceutil/trace.go:171","msg":"trace[508653533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15718; }","duration":"117.041914ms","start":"2026-09-08T16:19:23.586881Z","end":"2026-09-08T16:19:23.703922Z","steps":["trace[508653533] 'agreement among raft nodes before linearized reading' (duration: 116.698035ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:19:23.817134Z","caller":"traceutil/trace.go:171","msg":"trace[563100201] transaction","detail":"{read_only:false; response_revision:15720; number_of_response:1; }","duration":"103.731712ms","start":"2026-09-08T16:19:23.71338Z","end":"2026-09-08T16:19:23.817111Z","steps":["trace[563100201] 'process raft request' (duration: 91.280893ms)","trace[563100201] 'compare' (duration: 11.985486ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:19:23.817307Z","caller":"traceutil/trace.go:171","msg":"trace[1215537675] transaction","detail":"{read_only:false; response_revision:15721; number_of_response:1; }","duration":"103.741932ms","start":"2026-09-08T16:19:23.713558Z","end":"2026-09-08T16:19:23.817299Z","steps":["trace[1215537675] 'process raft request' (duration: 103.214097ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:23:02.360154Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14959} {"level":"info","ts":"2026-09-08T16:23:02.465197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14959,"took":"101.489005ms","hash":3199648257,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":29974528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T16:23:02.465263Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3199648257,"revision":14959,"compact-revision":12702} {"level":"info","ts":"2026-09-08T16:25:01.138553Z","caller":"traceutil/trace.go:171","msg":"trace[1476974764] transaction","detail":"{read_only:false; response_revision:18166; number_of_response:1; }","duration":"156.281712ms","start":"2026-09-08T16:25:00.98222Z","end":"2026-09-08T16:25:01.138502Z","steps":["trace[1476974764] 'process raft request' (duration: 156.056816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:02.052163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.449848ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-08T16:25:02.052609Z","caller":"traceutil/trace.go:171","msg":"trace[1046395736] linearizableReadLoop","detail":"{readStateIndex:18795; appliedIndex:18794; }","duration":"574.391673ms","start":"2026-09-08T16:25:01.478153Z","end":"2026-09-08T16:25:02.052544Z","steps":["trace[1046395736] 'read index received' (duration: 37.481µs)","trace[1046395736] 'applied index is now lower than readState.Index' (duration: 574.350372ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:25:02.052667Z","caller":"traceutil/trace.go:171","msg":"trace[677023254] transaction","detail":"{read_only:false; response_revision:18168; number_of_response:1; }","duration":"277.50405ms","start":"2026-09-08T16:25:01.775139Z","end":"2026-09-08T16:25:02.052643Z","steps":["trace[677023254] 'process raft request' (duration: 277.285944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:02.053047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.856286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jhjnh\" ","response":"range_response_count:1 size:20752"} {"level":"warn","ts":"2026-09-08T16:25:02.053102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.288559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:25:02.053125Z","caller":"traceutil/trace.go:171","msg":"trace[293266201] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-jhjnh; range_end:; response_count:1; response_revision:18168; }","duration":"574.959189ms","start":"2026-09-08T16:25:01.478145Z","end":"2026-09-08T16:25:02.053104Z","steps":["trace[293266201] 'agreement among raft nodes before linearized reading' (duration: 574.620509ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:25:02.053153Z","caller":"traceutil/trace.go:171","msg":"trace[1480287162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18168; }","duration":"366.34434ms","start":"2026-09-08T16:25:01.686793Z","end":"2026-09-08T16:25:02.053137Z","steps":["trace[1480287162] 'agreement among raft nodes before linearized reading' (duration: 366.242068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:02.054467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:25:01.686715Z","time spent":"367.72475ms","remote":"127.0.0.1:40158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-08T16:25:02.053165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.458548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:25:02.054921Z","caller":"traceutil/trace.go:171","msg":"trace[258387754] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:18168; }","duration":"152.275339ms","start":"2026-09-08T16:25:01.902608Z","end":"2026-09-08T16:25:02.054883Z","steps":["trace[258387754] 'agreement among raft nodes before linearized reading' (duration: 150.431357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:02.05319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:25:01.478068Z","time spent":"575.107043ms","remote":"127.0.0.1:40336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20774,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jhjnh\" "} {"level":"warn","ts":"2026-09-08T16:25:02.053368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.741512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-08T16:25:02.055276Z","caller":"traceutil/trace.go:171","msg":"trace[1766787571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18168; }","duration":"215.674246ms","start":"2026-09-08T16:25:01.839563Z","end":"2026-09-08T16:25:02.055237Z","steps":["trace[1766787571] 'agreement among raft nodes before linearized reading' (duration: 213.677459ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:02.053454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.887248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-08T16:25:02.055516Z","caller":"traceutil/trace.go:171","msg":"trace[1515953906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18168; }","duration":"219.981408ms","start":"2026-09-08T16:25:01.83551Z","end":"2026-09-08T16:25:02.055492Z","steps":["trace[1515953906] 'agreement among raft nodes before linearized reading' (duration: 217.849438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:02.053484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.735806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-08T16:25:02.055653Z","caller":"traceutil/trace.go:171","msg":"trace[1004505340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18168; }","duration":"172.922398ms","start":"2026-09-08T16:25:01.882698Z","end":"2026-09-08T16:25:02.05562Z","steps":["trace[1004505340] 'agreement among raft nodes before linearized reading' (duration: 170.696256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:02.053581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.725328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-08T16:25:02.055818Z","caller":"traceutil/trace.go:171","msg":"trace[1824855063] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18168; }","duration":"228.032493ms","start":"2026-09-08T16:25:01.827774Z","end":"2026-09-08T16:25:02.055806Z","steps":["trace[1824855063] 'agreement among raft nodes before linearized reading' (duration: 225.705967ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:25:02.208637Z","caller":"traceutil/trace.go:171","msg":"trace[1542596844] linearizableReadLoop","detail":"{readStateIndex:18802; appliedIndex:18797; }","duration":"110.445137ms","start":"2026-09-08T16:25:02.098162Z","end":"2026-09-08T16:25:02.208607Z","steps":["trace[1542596844] 'read index received' (duration: 47.393219ms)","trace[1542596844] 'applied index is now lower than readState.Index' (duration: 63.050658ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:25:02.208714Z","caller":"traceutil/trace.go:171","msg":"trace[769704569] transaction","detail":"{read_only:false; response_revision:18174; number_of_response:1; }","duration":"124.219453ms","start":"2026-09-08T16:25:02.084471Z","end":"2026-09-08T16:25:02.20869Z","steps":["trace[769704569] 'process raft request' (duration: 123.803472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:02.208923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.742706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-9wf27\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-09-08T16:25:02.208957Z","caller":"traceutil/trace.go:171","msg":"trace[350052356] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-9wf27; range_end:; response_count:1; response_revision:18175; }","duration":"110.848148ms","start":"2026-09-08T16:25:02.098098Z","end":"2026-09-08T16:25:02.208946Z","steps":["trace[350052356] 'agreement among raft nodes before linearized reading' (duration: 110.621492ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:27:05.906611Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db28a37eabf8270","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T16:27:05.91252Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-08T16:27:05.912728Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-08T16:28:02.37148Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17339} {"level":"info","ts":"2026-09-08T16:28:02.475074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17339,"took":"99.265184ms","hash":2163201793,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":33964032,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-08T16:28:02.475154Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2163201793,"revision":17339,"compact-revision":14959} {"level":"warn","ts":"2026-09-08T16:31:11.446068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.496239ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-08T16:31:12.039086Z","caller":"traceutil/trace.go:171","msg":"trace[1558296505] transaction","detail":"{read_only:false; response_revision:20971; number_of_response:1; }","duration":"161.31185ms","start":"2026-09-08T16:31:11.877737Z","end":"2026-09-08T16:31:12.039049Z","steps":["trace[1558296505] 'process raft request' (duration: 160.905218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:31:12.946036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.636716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T16:31:12.946186Z","caller":"traceutil/trace.go:171","msg":"trace[1416966662] transaction","detail":"{read_only:false; response_revision:20974; number_of_response:1; }","duration":"282.737841ms","start":"2026-09-08T16:31:12.663418Z","end":"2026-09-08T16:31:12.946156Z","steps":["trace[1416966662] 'process raft request' (duration: 165.705163ms)","trace[1416966662] 'compare' (duration: 115.359661ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:31:12.947095Z","caller":"traceutil/trace.go:171","msg":"trace[651490989] transaction","detail":"{read_only:false; response_revision:20975; number_of_response:1; }","duration":"283.658507ms","start":"2026-09-08T16:31:12.663418Z","end":"2026-09-08T16:31:12.947076Z","steps":["trace[651490989] 'process raft request' (duration: 283.314098ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:31:12.947582Z","caller":"traceutil/trace.go:171","msg":"trace[894548258] linearizableReadLoop","detail":"{readStateIndex:21696; appliedIndex:21693; }","duration":"283.78065ms","start":"2026-09-08T16:31:12.663786Z","end":"2026-09-08T16:31:12.947566Z","steps":["trace[894548258] 'read index received' (duration: 165.325762ms)","trace[894548258] 'applied index is now lower than readState.Index' (duration: 118.453868ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:31:12.947626Z","caller":"traceutil/trace.go:171","msg":"trace[697081692] transaction","detail":"{read_only:false; response_revision:20977; number_of_response:1; }","duration":"283.284866ms","start":"2026-09-08T16:31:12.664297Z","end":"2026-09-08T16:31:12.947582Z","steps":["trace[697081692] 'process raft request' (duration: 283.029719ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:31:12.947838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.043637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:31:12.947667Z","caller":"traceutil/trace.go:171","msg":"trace[973400903] transaction","detail":"{read_only:false; response_revision:20976; number_of_response:1; }","duration":"284.13795ms","start":"2026-09-08T16:31:12.663488Z","end":"2026-09-08T16:31:12.947626Z","steps":["trace[973400903] 'process raft request' (duration: 283.488082ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:31:12.94793Z","caller":"traceutil/trace.go:171","msg":"trace[225218014] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20977; }","duration":"284.16676ms","start":"2026-09-08T16:31:12.663745Z","end":"2026-09-08T16:31:12.947912Z","steps":["trace[225218014] 'agreement among raft nodes before linearized reading' (duration: 283.945054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:31:12.948124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.517744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:31:12.948237Z","caller":"traceutil/trace.go:171","msg":"trace[2122666924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20979; }","duration":"262.694689ms","start":"2026-09-08T16:31:12.685517Z","end":"2026-09-08T16:31:12.948211Z","steps":["trace[2122666924] 'agreement among raft nodes before linearized reading' (duration: 262.501964ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:32:25.622113Z","caller":"traceutil/trace.go:171","msg":"trace[738372259] transaction","detail":"{read_only:false; response_revision:21620; number_of_response:1; }","duration":"108.080808ms","start":"2026-09-08T16:32:25.51398Z","end":"2026-09-08T16:32:25.62206Z","steps":["trace[738372259] 'process raft request' (duration: 107.820991ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:33:02.380248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19706} {"level":"info","ts":"2026-09-08T16:33:02.510036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19706,"took":"124.711874ms","hash":3979912816,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":29413376,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-08T16:33:02.510457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3979912816,"revision":19706,"compact-revision":17339} {"level":"info","ts":"2026-09-08T16:35:03.520461Z","caller":"traceutil/trace.go:171","msg":"trace[778336789] linearizableReadLoop","detail":"{readStateIndex:24146; appliedIndex:24145; }","duration":"105.651829ms","start":"2026-09-08T16:35:03.414779Z","end":"2026-09-08T16:35:03.520431Z","steps":["trace[778336789] 'read index received' (duration: 32.774727ms)","trace[778336789] 'applied index is now lower than readState.Index' (duration: 72.875691ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:35:03.520683Z","caller":"traceutil/trace.go:171","msg":"trace[1199792649] transaction","detail":"{read_only:false; response_revision:23354; number_of_response:1; }","duration":"138.702276ms","start":"2026-09-08T16:35:03.381921Z","end":"2026-09-08T16:35:03.520623Z","steps":["trace[1199792649] 'process raft request' (duration: 65.740462ms)","trace[1199792649] 'compare' (duration: 72.602823ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:35:03.520828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.01144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:35:03.520959Z","caller":"traceutil/trace.go:171","msg":"trace[282930509] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:23354; }","duration":"106.214855ms","start":"2026-09-08T16:35:03.414721Z","end":"2026-09-08T16:35:03.520936Z","steps":["trace[282930509] 'agreement among raft nodes before linearized reading' (duration: 105.920777ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:35:03.916654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.499439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T16:35:03.917114Z","caller":"traceutil/trace.go:171","msg":"trace[172831073] linearizableReadLoop","detail":"{readStateIndex:24149; appliedIndex:24148; }","duration":"289.402057ms","start":"2026-09-08T16:35:03.627555Z","end":"2026-09-08T16:35:03.916957Z","steps":["trace[172831073] 'read index received' (duration: 168.633534ms)","trace[172831073] 'applied index is now lower than readState.Index' (duration: 120.766283ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:35:03.917511Z","caller":"traceutil/trace.go:171","msg":"trace[564015794] transaction","detail":"{read_only:false; response_revision:23357; number_of_response:1; }","duration":"385.990923ms","start":"2026-09-08T16:35:03.531499Z","end":"2026-09-08T16:35:03.91749Z","steps":["trace[564015794] 'process raft request' (duration: 264.784048ms)","trace[564015794] 'compare' (duration: 118.083558ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:35:03.918012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:35:03.531472Z","time spent":"386.214079ms","remote":"127.0.0.1:40336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4783,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:35:03.918617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.035293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager\" ","response":"range_response_count:1 size:5176"} {"level":"info","ts":"2026-09-08T16:35:03.91873Z","caller":"traceutil/trace.go:171","msg":"trace[591020689] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager; range_end:; response_count:1; response_revision:23357; }","duration":"291.214598ms","start":"2026-09-08T16:35:03.627482Z","end":"2026-09-08T16:35:03.918697Z","steps":["trace[591020689] 'agreement among raft nodes before linearized reading' (duration: 290.828698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:35:03.919224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.926101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackfloatingippools.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:10419"} {"level":"info","ts":"2026-09-08T16:35:03.919272Z","caller":"traceutil/trace.go:171","msg":"trace[2089302721] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackfloatingippools.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23357; }","duration":"291.016393ms","start":"2026-09-08T16:35:03.628245Z","end":"2026-09-08T16:35:03.919261Z","steps":["trace[2089302721] 'agreement among raft nodes before linearized reading' (duration: 290.890159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:35:03.919546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.536662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:35:03.919577Z","caller":"traceutil/trace.go:171","msg":"trace[551903499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23357; }","duration":"233.626564ms","start":"2026-09-08T16:35:03.685941Z","end":"2026-09-08T16:35:03.919567Z","steps":["trace[551903499] 'agreement among raft nodes before linearized reading' (duration: 233.567443ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:38:02.38963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21879} {"level":"info","ts":"2026-09-08T16:38:02.511756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21879,"took":"117.728107ms","hash":2898131320,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":43216896,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-08T16:38:02.511846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2898131320,"revision":21879,"compact-revision":19706} {"level":"info","ts":"2026-09-08T16:43:02.399106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25034} {"level":"info","ts":"2026-09-08T16:43:02.560631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25034,"took":"156.9738ms","hash":2810300743,"current-db-size-bytes":52850688,"current-db-size":"53 MB","current-db-size-in-use-bytes":29597696,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T16:43:02.560719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2810300743,"revision":25034,"compact-revision":21879}