{"level":"warn","ts":"2026-10-09T05:23:06.601122Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T05:23:06.601235Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.187:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.187:2380","--initial-cluster=instance=https://199.204.45.187:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.187:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.187: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-10-09T05:23:06.601317Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T05:23:06.601332Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.187:2380"]} {"level":"info","ts":"2026-10-09T05:23:06.601355Z","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-10-09T05:23:06.602445Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.187:2379"]} {"level":"info","ts":"2026-10-09T05:23:06.602539Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.187:2380"],"listen-peer-urls":["https://199.204.45.187:2380"],"advertise-client-urls":["https://199.204.45.187:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.187:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.187: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-10-09T05:23:06.60646Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.6474ms"} {"level":"info","ts":"2026-10-09T05:23:06.611894Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b9fef886b531fede","cluster-id":"a229ea8278bf4f99"} {"level":"info","ts":"2026-10-09T05:23:06.612037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b9fef886b531fede switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T05:23:06.612072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b9fef886b531fede became follower at term 0"} {"level":"info","ts":"2026-10-09T05:23:06.612085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b9fef886b531fede [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T05:23:06.612105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b9fef886b531fede became follower at term 1"} {"level":"info","ts":"2026-10-09T05:23:06.612154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b9fef886b531fede switched to configuration voters=(13402422798550433502)"} {"level":"warn","ts":"2026-10-09T05:23:06.617362Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T05:23:06.618965Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T05:23:06.620219Z","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-10-09T05:23:06.622882Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b9fef886b531fede","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T05:23:06.623212Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b9fef886b531fede","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T05:23:06.623502Z","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-10-09T05:23:06.623696Z","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-10-09T05:23:06.62372Z","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-10-09T05:23:06.626452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b9fef886b531fede switched to configuration voters=(13402422798550433502)"} {"level":"info","ts":"2026-10-09T05:23:06.626722Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a229ea8278bf4f99","local-member-id":"b9fef886b531fede","added-peer-id":"b9fef886b531fede","added-peer-peer-urls":["https://199.204.45.187:2380"]} {"level":"info","ts":"2026-10-09T05:23:06.63121Z","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-10-09T05:23:06.631551Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.187:2380"} {"level":"info","ts":"2026-10-09T05:23:06.631653Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.187:2380"} {"level":"info","ts":"2026-10-09T05:23:06.631777Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b9fef886b531fede","initial-advertise-peer-urls":["https://199.204.45.187:2380"],"listen-peer-urls":["https://199.204.45.187:2380"],"advertise-client-urls":["https://199.204.45.187:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.187:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T05:23:06.631839Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T05:23:06.713059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b9fef886b531fede is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T05:23:06.713137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b9fef886b531fede became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T05:23:06.713182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b9fef886b531fede received MsgPreVoteResp from b9fef886b531fede at term 1"} {"level":"info","ts":"2026-10-09T05:23:06.713199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b9fef886b531fede became candidate at term 2"} {"level":"info","ts":"2026-10-09T05:23:06.713208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b9fef886b531fede received MsgVoteResp from b9fef886b531fede at term 2"} {"level":"info","ts":"2026-10-09T05:23:06.713218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b9fef886b531fede became leader at term 2"} {"level":"info","ts":"2026-10-09T05:23:06.713231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b9fef886b531fede elected leader b9fef886b531fede at term 2"} {"level":"info","ts":"2026-10-09T05:23:06.714252Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:23:06.715399Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T05:23:06.715384Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b9fef886b531fede","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.187:2379]}","request-path":"/0/members/b9fef886b531fede/attributes","cluster-id":"a229ea8278bf4f99","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T05:23:06.715518Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T05:23:06.71579Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a229ea8278bf4f99","local-member-id":"b9fef886b531fede","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:23:06.715798Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T05:23:06.715893Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:23:06.715993Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:23:06.715968Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T05:23:06.719524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T05:23:06.720055Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.187:2379"} {"level":"info","ts":"2026-10-09T05:23:24.655761Z","caller":"traceutil/trace.go:171","msg":"trace[1884401548] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"154.301084ms","start":"2026-10-09T05:23:24.50142Z","end":"2026-10-09T05:23:24.655721Z","steps":["trace[1884401548] 'read index received' (duration: 153.909138ms)","trace[1884401548] 'applied index is now lower than readState.Index' (duration: 390.766µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:23:24.655843Z","caller":"traceutil/trace.go:171","msg":"trace[1150693736] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"172.318221ms","start":"2026-10-09T05:23:24.483477Z","end":"2026-10-09T05:23:24.655795Z","steps":["trace[1150693736] 'process raft request' (duration: 171.953834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:23:24.656003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.545279ms","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-10-09T05:23:24.656092Z","caller":"traceutil/trace.go:171","msg":"trace[1147634678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"154.701512ms","start":"2026-10-09T05:23:24.501375Z","end":"2026-10-09T05:23:24.656076Z","steps":["trace[1147634678] 'agreement among raft nodes before linearized reading' (duration: 154.504538ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:23:53.335231Z","caller":"traceutil/trace.go:171","msg":"trace[1612086477] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"199.191409ms","start":"2026-10-09T05:23:53.136008Z","end":"2026-10-09T05:23:53.3352Z","steps":["trace[1612086477] 'process raft request' (duration: 199.089747ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:23:53.335273Z","caller":"traceutil/trace.go:171","msg":"trace[1984704105] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"199.607117ms","start":"2026-10-09T05:23:53.135635Z","end":"2026-10-09T05:23:53.335242Z","steps":["trace[1984704105] 'process raft request' (duration: 164.222997ms)","trace[1984704105] 'compare' (duration: 35.040883ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:23:53.33938Z","caller":"traceutil/trace.go:171","msg":"trace[1131320268] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"120.611908ms","start":"2026-10-09T05:23:53.218744Z","end":"2026-10-09T05:23:53.339356Z","steps":["trace[1131320268] 'process raft request' (duration: 120.075457ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:23:59.822479Z","caller":"traceutil/trace.go:171","msg":"trace[1142171305] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"111.794779ms","start":"2026-10-09T05:23:59.710658Z","end":"2026-10-09T05:23:59.822453Z","steps":["trace[1142171305] 'process raft request' (duration: 111.653456ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:24:00.300471Z","caller":"traceutil/trace.go:171","msg":"trace[1442889063] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"162.735877ms","start":"2026-10-09T05:24:00.137708Z","end":"2026-10-09T05:24:00.300444Z","steps":["trace[1442889063] 'read index received' (duration: 161.458242ms)","trace[1442889063] 'applied index is now lower than readState.Index' (duration: 1.276625ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:24:00.300532Z","caller":"traceutil/trace.go:171","msg":"trace[1684154005] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"255.352338ms","start":"2026-10-09T05:24:00.045155Z","end":"2026-10-09T05:24:00.300508Z","steps":["trace[1684154005] 'process raft request' (duration: 254.144434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:24:00.318859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.05911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rxl7f\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-09T05:24:00.319265Z","caller":"traceutil/trace.go:171","msg":"trace[1307438441] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rxl7f; range_end:; response_count:1; response_revision:615; }","duration":"181.401096ms","start":"2026-10-09T05:24:00.137661Z","end":"2026-10-09T05:24:00.319062Z","steps":["trace[1307438441] 'agreement among raft nodes before linearized reading' (duration: 162.909101ms)","trace[1307438441] 'range keys from in-memory index tree' (duration: 17.922135ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:24:00.638892Z","caller":"traceutil/trace.go:171","msg":"trace[1770183614] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"298.492136ms","start":"2026-10-09T05:24:00.340348Z","end":"2026-10-09T05:24:00.63884Z","steps":["trace[1770183614] 'read index received' (duration: 278.292588ms)","trace[1770183614] 'applied index is now lower than readState.Index' (duration: 20.197888ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:24:00.638943Z","caller":"traceutil/trace.go:171","msg":"trace[2104165452] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"298.845173ms","start":"2026-10-09T05:24:00.340028Z","end":"2026-10-09T05:24:00.638873Z","steps":["trace[2104165452] 'process raft request' (duration: 278.543692ms)","trace[2104165452] 'compare' (duration: 20.112017ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:24:00.639154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.744161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-09T05:24:00.639196Z","caller":"traceutil/trace.go:171","msg":"trace[2114167611] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:617; }","duration":"298.860423ms","start":"2026-10-09T05:24:00.340322Z","end":"2026-10-09T05:24:00.639183Z","steps":["trace[2114167611] 'agreement among raft nodes before linearized reading' (duration: 298.70882ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:24:00.727372Z","caller":"traceutil/trace.go:171","msg":"trace[614588272] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"349.318274ms","start":"2026-10-09T05:24:00.37803Z","end":"2026-10-09T05:24:00.727348Z","steps":["trace[614588272] 'process raft request' (duration: 349.14561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:24:00.727659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.654735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:24:00.727799Z","caller":"traceutil/trace.go:171","msg":"trace[1476100607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"329.879839ms","start":"2026-10-09T05:24:00.39789Z","end":"2026-10-09T05:24:00.72777Z","steps":["trace[1476100607] 'agreement among raft nodes before linearized reading' (duration: 329.600994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:24:00.727865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:24:00.397868Z","time spent":"329.986592ms","remote":"127.0.0.1:35364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T05:24:00.727874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:24:00.378004Z","time spent":"349.458375ms","remote":"127.0.0.1:35602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T05:25:36.898917Z","caller":"traceutil/trace.go:171","msg":"trace[1576006063] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"191.318691ms","start":"2026-10-09T05:25:36.707569Z","end":"2026-10-09T05:25:36.898888Z","steps":["trace[1576006063] 'process raft request' (duration: 191.152137ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:37.000009Z","caller":"traceutil/trace.go:171","msg":"trace[177019014] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"134.660889ms","start":"2026-10-09T05:25:36.865317Z","end":"2026-10-09T05:25:36.999978Z","steps":["trace[177019014] 'process raft request' (duration: 69.024377ms)","trace[177019014] 'compare' (duration: 65.457099ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:25:52.011857Z","caller":"traceutil/trace.go:171","msg":"trace[699046972] linearizableReadLoop","detail":"{readStateIndex:2080; appliedIndex:2079; }","duration":"109.588234ms","start":"2026-10-09T05:25:51.902233Z","end":"2026-10-09T05:25:52.011821Z","steps":["trace[699046972] 'read index received' (duration: 109.388881ms)","trace[699046972] 'applied index is now lower than readState.Index' (duration: 197.933µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:25:52.01202Z","caller":"traceutil/trace.go:171","msg":"trace[496090890] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"124.338192ms","start":"2026-10-09T05:25:51.887634Z","end":"2026-10-09T05:25:52.011972Z","steps":["trace[496090890] 'process raft request' (duration: 123.995507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:25:52.012228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.916341ms","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-10-09T05:25:52.012413Z","caller":"traceutil/trace.go:171","msg":"trace[393098330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1998; }","duration":"110.198495ms","start":"2026-10-09T05:25:51.902193Z","end":"2026-10-09T05:25:52.012392Z","steps":["trace[393098330] 'agreement among raft nodes before linearized reading' (duration: 109.793578ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:26:21.291353Z","caller":"traceutil/trace.go:171","msg":"trace[2014974392] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"120.267996ms","start":"2026-10-09T05:26:21.171057Z","end":"2026-10-09T05:26:21.291325Z","steps":["trace[2014974392] 'process raft request' (duration: 120.103793ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:26:27.445198Z","caller":"traceutil/trace.go:171","msg":"trace[1026432115] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"129.265555ms","start":"2026-10-09T05:26:27.315883Z","end":"2026-10-09T05:26:27.445149Z","steps":["trace[1026432115] 'process raft request' (duration: 129.00566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:26:32.930241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.923261ms","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-10-09T05:26:32.930384Z","caller":"traceutil/trace.go:171","msg":"trace[1915942526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2223; }","duration":"230.140585ms","start":"2026-10-09T05:26:32.700209Z","end":"2026-10-09T05:26:32.930349Z","steps":["trace[1915942526] 'range keys from in-memory index tree' (duration: 229.642876ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:27:20.306538Z","caller":"traceutil/trace.go:171","msg":"trace[344286450] linearizableReadLoop","detail":"{readStateIndex:2759; appliedIndex:2758; }","duration":"121.796337ms","start":"2026-10-09T05:27:20.184716Z","end":"2026-10-09T05:27:20.306512Z","steps":["trace[344286450] 'read index received' (duration: 121.632745ms)","trace[344286450] 'applied index is now lower than readState.Index' (duration: 162.472µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:27:20.306591Z","caller":"traceutil/trace.go:171","msg":"trace[893860021] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"182.893979ms","start":"2026-10-09T05:27:20.123663Z","end":"2026-10-09T05:27:20.306557Z","steps":["trace[893860021] 'process raft request' (duration: 182.687456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:27:20.306854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.104202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-09T05:27:20.306984Z","caller":"traceutil/trace.go:171","msg":"trace[801249382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2651; }","duration":"122.273056ms","start":"2026-10-09T05:27:20.184686Z","end":"2026-10-09T05:27:20.306959Z","steps":["trace[801249382] 'agreement among raft nodes before linearized reading' (duration: 121.96643ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:27:53.991994Z","caller":"traceutil/trace.go:171","msg":"trace[1750833822] transaction","detail":"{read_only:false; response_revision:2855; number_of_response:1; }","duration":"168.116737ms","start":"2026-10-09T05:27:53.823848Z","end":"2026-10-09T05:27:53.991965Z","steps":["trace[1750833822] 'process raft request' (duration: 121.690355ms)","trace[1750833822] 'compare' (duration: 46.255919ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:27:54.182195Z","caller":"traceutil/trace.go:171","msg":"trace[669600383] transaction","detail":"{read_only:false; response_revision:2856; number_of_response:1; }","duration":"128.003387ms","start":"2026-10-09T05:27:54.054162Z","end":"2026-10-09T05:27:54.182165Z","steps":["trace[669600383] 'process raft request' (duration: 40.968245ms)","trace[669600383] 'compare' (duration: 86.91726ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:27:54.196393Z","caller":"traceutil/trace.go:171","msg":"trace[329750384] transaction","detail":"{read_only:false; response_revision:2857; number_of_response:1; }","duration":"138.202488ms","start":"2026-10-09T05:27:54.05816Z","end":"2026-10-09T05:27:54.196362Z","steps":["trace[329750384] 'process raft request' (duration: 138.034075ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:32:43.517219Z","caller":"traceutil/trace.go:171","msg":"trace[1722556847] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"165.458753ms","start":"2026-10-09T05:32:43.351702Z","end":"2026-10-09T05:32:43.51716Z","steps":["trace[1722556847] 'process raft request' (duration: 164.861031ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:32:43.517342Z","caller":"traceutil/trace.go:171","msg":"trace[1284824512] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"160.214184ms","start":"2026-10-09T05:32:43.357046Z","end":"2026-10-09T05:32:43.517261Z","steps":["trace[1284824512] 'process raft request' (duration: 159.929978ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:32:43.517588Z","caller":"traceutil/trace.go:171","msg":"trace[1856512137] linearizableReadLoop","detail":"{readStateIndex:5409; appliedIndex:5406; }","duration":"151.990729ms","start":"2026-10-09T05:32:43.365583Z","end":"2026-10-09T05:32:43.517573Z","steps":["trace[1856512137] 'read index received' (duration: 150.783416ms)","trace[1856512137] 'applied index is now lower than readState.Index' (duration: 1.205623ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:32:43.517681Z","caller":"traceutil/trace.go:171","msg":"trace[756693651] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"155.116738ms","start":"2026-10-09T05:32:43.36253Z","end":"2026-10-09T05:32:43.517647Z","steps":["trace[756693651] 'process raft request' (duration: 154.638269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:32:43.517927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.324646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-09T05:32:43.517971Z","caller":"traceutil/trace.go:171","msg":"trace[1252295524] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5204; }","duration":"152.419457ms","start":"2026-10-09T05:32:43.365541Z","end":"2026-10-09T05:32:43.51796Z","steps":["trace[1252295524] 'agreement among raft nodes before linearized reading' (duration: 152.115121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:32:43.51819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.860927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:75510"} {"level":"warn","ts":"2026-10-09T05:32:43.518266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.185103ms","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-10-09T05:32:43.518316Z","caller":"traceutil/trace.go:171","msg":"trace[170378848] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5204; }","duration":"116.304925ms","start":"2026-10-09T05:32:43.401996Z","end":"2026-10-09T05:32:43.518301Z","steps":["trace[170378848] 'agreement among raft nodes before linearized reading' (duration: 116.198514ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:32:43.518304Z","caller":"traceutil/trace.go:171","msg":"trace[1628594858] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:5204; }","duration":"151.994039ms","start":"2026-10-09T05:32:43.366286Z","end":"2026-10-09T05:32:43.51828Z","steps":["trace[1628594858] 'agreement among raft nodes before linearized reading' (duration: 151.640933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:32:48.36792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.880509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8ee8947b-05b4-4e8f-ab7d-0a00d61c21be\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-09T05:32:48.368058Z","caller":"traceutil/trace.go:171","msg":"trace[1627779355] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8ee8947b-05b4-4e8f-ab7d-0a00d61c21be; range_end:; response_count:1; response_revision:5340; }","duration":"177.092273ms","start":"2026-10-09T05:32:48.190937Z","end":"2026-10-09T05:32:48.368029Z","steps":["trace[1627779355] 'range keys from in-memory index tree' (duration: 176.554373ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:33:04.121528Z","caller":"traceutil/trace.go:171","msg":"trace[104259814] transaction","detail":"{read_only:false; response_revision:5649; number_of_response:1; }","duration":"376.409896ms","start":"2026-10-09T05:33:03.745095Z","end":"2026-10-09T05:33:04.121504Z","steps":["trace[104259814] 'process raft request' (duration: 333.566487ms)","trace[104259814] 'compare' (duration: 42.686316ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:33:04.121637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.013888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:33:04.121682Z","caller":"traceutil/trace.go:171","msg":"trace[544095545] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:5649; }","duration":"207.11985ms","start":"2026-10-09T05:33:03.914548Z","end":"2026-10-09T05:33:04.121667Z","steps":["trace[544095545] 'agreement among raft nodes before linearized reading' (duration: 207.024868ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:33:04.121504Z","caller":"traceutil/trace.go:171","msg":"trace[2058851136] linearizableReadLoop","detail":"{readStateIndex:5868; appliedIndex:5867; }","duration":"206.873465ms","start":"2026-10-09T05:33:03.914598Z","end":"2026-10-09T05:33:04.121472Z","steps":["trace[2058851136] 'read index received' (duration: 164.002956ms)","trace[2058851136] 'applied index is now lower than readState.Index' (duration: 42.868809ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:33:04.121907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:33:03.745068Z","time spent":"376.532208ms","remote":"127.0.0.1:58464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7599,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:33:04.414287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.938827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:33:04.414412Z","caller":"traceutil/trace.go:171","msg":"trace[1584516908] linearizableReadLoop","detail":"{readStateIndex:5869; appliedIndex:5868; }","duration":"221.958939ms","start":"2026-10-09T05:33:04.192434Z","end":"2026-10-09T05:33:04.414393Z","steps":["trace[1584516908] 'read index received' (duration: 85.552125ms)","trace[1584516908] 'applied index is now lower than readState.Index' (duration: 136.405254ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:33:04.414513Z","caller":"traceutil/trace.go:171","msg":"trace[1299351833] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"237.286579ms","start":"2026-10-09T05:33:04.177216Z","end":"2026-10-09T05:33:04.414502Z","steps":["trace[1299351833] 'process raft request' (duration: 100.852234ms)","trace[1299351833] 'compare' (duration: 135.60265ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:33:04.414713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.248975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-10-09T05:33:04.414825Z","caller":"traceutil/trace.go:171","msg":"trace[1644205436] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5650; }","duration":"222.422858ms","start":"2026-10-09T05:33:04.192378Z","end":"2026-10-09T05:33:04.414801Z","steps":["trace[1644205436] 'agreement among raft nodes before linearized reading' (duration: 222.147583ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:33:07.063426Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2930} {"level":"info","ts":"2026-10-09T05:33:07.14945Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2930,"took":"83.74741ms","hash":2996532458,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":31694848,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T05:33:07.149542Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2996532458,"revision":2930,"compact-revision":-1} {"level":"info","ts":"2026-10-09T05:33:12.630861Z","caller":"traceutil/trace.go:171","msg":"trace[1808887586] transaction","detail":"{read_only:false; response_revision:5817; number_of_response:1; }","duration":"153.461406ms","start":"2026-10-09T05:33:12.477369Z","end":"2026-10-09T05:33:12.630831Z","steps":["trace[1808887586] 'process raft request' (duration: 139.013034ms)","trace[1808887586] 'compare' (duration: 14.189348ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:33:12.646429Z","caller":"traceutil/trace.go:171","msg":"trace[1224280758] linearizableReadLoop","detail":"{readStateIndex:6041; appliedIndex:6038; }","duration":"120.328751ms","start":"2026-10-09T05:33:12.526065Z","end":"2026-10-09T05:33:12.646394Z","steps":["trace[1224280758] 'read index received' (duration: 90.317315ms)","trace[1224280758] 'applied index is now lower than readState.Index' (duration: 30.009946ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:33:12.646513Z","caller":"traceutil/trace.go:171","msg":"trace[1364013842] transaction","detail":"{read_only:false; response_revision:5819; number_of_response:1; }","duration":"167.123964ms","start":"2026-10-09T05:33:12.479361Z","end":"2026-10-09T05:33:12.646485Z","steps":["trace[1364013842] 'process raft request' (duration: 166.960921ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:33:12.646481Z","caller":"traceutil/trace.go:171","msg":"trace[1211050474] transaction","detail":"{read_only:false; response_revision:5818; number_of_response:1; }","duration":"168.343548ms","start":"2026-10-09T05:33:12.47811Z","end":"2026-10-09T05:33:12.646454Z","steps":["trace[1211050474] 'process raft request' (duration: 168.006901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:33:12.646711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.626877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1491d83f-e76b-4f72-9e2d-22acc705c832\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-09T05:33:12.646834Z","caller":"traceutil/trace.go:171","msg":"trace[573169973] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1491d83f-e76b-4f72-9e2d-22acc705c832; range_end:; response_count:1; response_revision:5819; }","duration":"120.82377ms","start":"2026-10-09T05:33:12.525987Z","end":"2026-10-09T05:33:12.646811Z","steps":["trace[573169973] 'agreement among raft nodes before linearized reading' (duration: 120.539315ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:34:21.757699Z","caller":"traceutil/trace.go:171","msg":"trace[552602734] transaction","detail":"{read_only:false; response_revision:6413; number_of_response:1; }","duration":"105.065533ms","start":"2026-10-09T05:34:21.6526Z","end":"2026-10-09T05:34:21.757665Z","steps":["trace[552602734] 'process raft request' (duration: 54.187453ms)","trace[552602734] 'compare' (duration: 50.557214ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:38:07.074201Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5689} {"level":"info","ts":"2026-10-09T05:38:07.174583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5689,"took":"98.038122ms","hash":3474270373,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":26865664,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-09T05:38:07.174664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3474270373,"revision":5689,"compact-revision":2930} {"level":"warn","ts":"2026-10-09T05:39:45.657426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.494953ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T05:39:45.657575Z","caller":"traceutil/trace.go:171","msg":"trace[615862620] linearizableReadLoop","detail":"{readStateIndex:9079; appliedIndex:9078; }","duration":"312.885573ms","start":"2026-10-09T05:39:45.344664Z","end":"2026-10-09T05:39:45.65755Z","steps":["trace[615862620] 'read index received' (duration: 41.174207ms)","trace[615862620] 'applied index is now lower than readState.Index' (duration: 271.709416ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:39:45.65786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.178139ms","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-10-09T05:39:45.657913Z","caller":"traceutil/trace.go:171","msg":"trace[1483247717] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8760; }","duration":"313.286771ms","start":"2026-10-09T05:39:45.344612Z","end":"2026-10-09T05:39:45.657899Z","steps":["trace[1483247717] 'agreement among raft nodes before linearized reading' (duration: 313.025836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:39:45.657947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:39:45.34459Z","time spent":"313.348822ms","remote":"127.0.0.1:35550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-09T05:39:45.70753Z","caller":"traceutil/trace.go:171","msg":"trace[557482225] transaction","detail":"{read_only:false; response_revision:8761; number_of_response:1; }","duration":"225.757003ms","start":"2026-10-09T05:39:45.481722Z","end":"2026-10-09T05:39:45.707479Z","steps":["trace[557482225] 'process raft request' (duration: 225.371855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:39:45.707766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.355607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:39:45.707848Z","caller":"traceutil/trace.go:171","msg":"trace[13726458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8761; }","duration":"310.520349ms","start":"2026-10-09T05:39:45.397309Z","end":"2026-10-09T05:39:45.707829Z","steps":["trace[13726458] 'agreement among raft nodes before linearized reading' (duration: 310.353906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:39:45.707904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:39:45.397284Z","time spent":"310.606671ms","remote":"127.0.0.1:35364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T05:39:45.708261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.396026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r9l59\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-09T05:39:45.708332Z","caller":"traceutil/trace.go:171","msg":"trace[197788495] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r9l59; range_end:; response_count:1; response_revision:8761; }","duration":"200.13564ms","start":"2026-10-09T05:39:45.508177Z","end":"2026-10-09T05:39:45.708313Z","steps":["trace[197788495] 'agreement among raft nodes before linearized reading' (duration: 199.269864ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:40:16.047669Z","caller":"traceutil/trace.go:171","msg":"trace[225364406] transaction","detail":"{read_only:false; response_revision:8979; number_of_response:1; }","duration":"162.872388ms","start":"2026-10-09T05:40:15.884769Z","end":"2026-10-09T05:40:16.047642Z","steps":["trace[225364406] 'process raft request' (duration: 160.230428ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:40:19.363979Z","caller":"traceutil/trace.go:171","msg":"trace[1551051345] transaction","detail":"{read_only:false; response_revision:8992; number_of_response:1; }","duration":"100.575686ms","start":"2026-10-09T05:40:19.263368Z","end":"2026-10-09T05:40:19.363943Z","steps":["trace[1551051345] 'process raft request' (duration: 100.325501ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:40:29.800601Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024ae.wal"} {"level":"info","ts":"2026-10-09T05:40:30.062254Z","caller":"traceutil/trace.go:171","msg":"trace[1773041442] transaction","detail":"{read_only:false; response_revision:9063; number_of_response:1; }","duration":"188.259956ms","start":"2026-10-09T05:40:29.873958Z","end":"2026-10-09T05:40:30.062218Z","steps":["trace[1773041442] 'process raft request' (duration: 188.052032ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:40:30.068834Z","caller":"traceutil/trace.go:171","msg":"trace[1179855898] transaction","detail":"{read_only:false; response_revision:9064; number_of_response:1; }","duration":"136.359639ms","start":"2026-10-09T05:40:29.932439Z","end":"2026-10-09T05:40:30.068798Z","steps":["trace[1179855898] 'process raft request' (duration: 136.139565ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:41:30.871057Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b9fef886b531fede","local-member-applied-index":10005,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T05:41:30.881579Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10005} {"level":"info","ts":"2026-10-09T05:41:30.881692Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5005} {"level":"info","ts":"2026-10-09T05:43:07.082015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8012} {"level":"info","ts":"2026-10-09T05:43:07.169604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8012,"took":"84.86149ms","hash":4117972977,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":24891392,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-09T05:43:07.169684Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4117972977,"revision":8012,"compact-revision":5689} {"level":"info","ts":"2026-10-09T05:47:44.407456Z","caller":"traceutil/trace.go:171","msg":"trace[1033850174] transaction","detail":"{read_only:false; response_revision:12380; number_of_response:1; }","duration":"134.348263ms","start":"2026-10-09T05:47:44.273073Z","end":"2026-10-09T05:47:44.407421Z","steps":["trace[1033850174] 'process raft request' (duration: 134.117478ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:47:44.416205Z","caller":"traceutil/trace.go:171","msg":"trace[183751924] transaction","detail":"{read_only:false; response_revision:12381; number_of_response:1; }","duration":"143.034808ms","start":"2026-10-09T05:47:44.273131Z","end":"2026-10-09T05:47:44.416166Z","steps":["trace[183751924] 'process raft request' (duration: 142.707761ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:48:07.090084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10382} {"level":"info","ts":"2026-10-09T05:48:07.177318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10382,"took":"84.357617ms","hash":1342362895,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":26230784,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T05:48:07.177418Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1342362895,"revision":10382,"compact-revision":8012} {"level":"info","ts":"2026-10-09T05:53:07.097824Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12546} {"level":"info","ts":"2026-10-09T05:53:07.18496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12546,"took":"84.047931ms","hash":182623125,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":27619328,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T05:53:07.18504Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":182623125,"revision":12546,"compact-revision":10382} {"level":"info","ts":"2026-10-09T05:55:00.931422Z","caller":"traceutil/trace.go:171","msg":"trace[1569453120] transaction","detail":"{read_only:false; response_revision:15426; number_of_response:1; }","duration":"116.214891ms","start":"2026-10-09T05:55:00.815179Z","end":"2026-10-09T05:55:00.931394Z","steps":["trace[1569453120] 'process raft request' (duration: 115.994477ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:55:01.041572Z","caller":"traceutil/trace.go:171","msg":"trace[1265239023] linearizableReadLoop","detail":"{readStateIndex:15974; appliedIndex:15973; }","duration":"101.707817ms","start":"2026-10-09T05:55:00.939822Z","end":"2026-10-09T05:55:01.04153Z","steps":["trace[1265239023] 'read index received' (duration: 89.912623ms)","trace[1265239023] 'applied index is now lower than readState.Index' (duration: 11.793124ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:55:01.041643Z","caller":"traceutil/trace.go:171","msg":"trace[1254513234] transaction","detail":"{read_only:false; response_revision:15427; number_of_response:1; }","duration":"105.170682ms","start":"2026-10-09T05:55:00.936414Z","end":"2026-10-09T05:55:01.041585Z","steps":["trace[1254513234] 'process raft request' (duration: 93.304798ms)","trace[1254513234] 'compare' (duration: 11.510078ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:55:01.041953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.151274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:55:01.042172Z","caller":"traceutil/trace.go:171","msg":"trace[326988322] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:15428; }","duration":"102.441239ms","start":"2026-10-09T05:55:00.939704Z","end":"2026-10-09T05:55:01.042146Z","steps":["trace[326988322] 'agreement among raft nodes before linearized reading' (duration: 101.995151ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:55:53.481406Z","caller":"traceutil/trace.go:171","msg":"trace[399638419] linearizableReadLoop","detail":"{readStateIndex:16620; appliedIndex:16619; }","duration":"100.341431ms","start":"2026-10-09T05:55:53.381035Z","end":"2026-10-09T05:55:53.481377Z","steps":["trace[399638419] 'read index received' (duration: 100.201518ms)","trace[399638419] 'applied index is now lower than readState.Index' (duration: 138.743µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:55:53.481556Z","caller":"traceutil/trace.go:171","msg":"trace[1521287398] transaction","detail":"{read_only:false; response_revision:16058; number_of_response:1; }","duration":"102.521523ms","start":"2026-10-09T05:55:53.379018Z","end":"2026-10-09T05:55:53.48154Z","steps":["trace[1521287398] 'process raft request' (duration: 102.198447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:55:53.481871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.8123ms","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-10-09T05:55:53.481924Z","caller":"traceutil/trace.go:171","msg":"trace[1645168879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16058; }","duration":"100.896321ms","start":"2026-10-09T05:55:53.381011Z","end":"2026-10-09T05:55:53.481908Z","steps":["trace[1645168879] 'agreement among raft nodes before linearized reading' (duration: 100.693537ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:56:27.807935Z","caller":"traceutil/trace.go:171","msg":"trace[204003136] linearizableReadLoop","detail":"{readStateIndex:16806; appliedIndex:16804; }","duration":"112.031133ms","start":"2026-10-09T05:56:27.695875Z","end":"2026-10-09T05:56:27.807906Z","steps":["trace[204003136] 'read index received' (duration: 12.147511ms)","trace[204003136] 'applied index is now lower than readState.Index' (duration: 99.879052ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:56:27.808029Z","caller":"traceutil/trace.go:171","msg":"trace[111122690] transaction","detail":"{read_only:false; response_revision:16237; number_of_response:1; }","duration":"149.025282ms","start":"2026-10-09T05:56:27.658959Z","end":"2026-10-09T05:56:27.807984Z","steps":["trace[111122690] 'process raft request' (duration: 148.769047ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:56:27.808196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.181166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-09T05:56:27.808205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.318518ms","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-10-09T05:56:27.808292Z","caller":"traceutil/trace.go:171","msg":"trace[797469690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16237; }","duration":"112.466271ms","start":"2026-10-09T05:56:27.695804Z","end":"2026-10-09T05:56:27.808271Z","steps":["trace[797469690] 'agreement among raft nodes before linearized reading' (duration: 112.210206ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:56:27.808244Z","caller":"traceutil/trace.go:171","msg":"trace[811101021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16237; }","duration":"102.275207ms","start":"2026-10-09T05:56:27.705954Z","end":"2026-10-09T05:56:27.80823Z","steps":["trace[811101021] 'agreement among raft nodes before linearized reading' (duration: 102.109184ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:56:27.950006Z","caller":"traceutil/trace.go:171","msg":"trace[1595019946] transaction","detail":"{read_only:false; response_revision:16239; number_of_response:1; }","duration":"136.687611ms","start":"2026-10-09T05:56:27.813291Z","end":"2026-10-09T05:56:27.949979Z","steps":["trace[1595019946] 'process raft request' (duration: 136.543638ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:56:27.950029Z","caller":"traceutil/trace.go:171","msg":"trace[909596617] transaction","detail":"{read_only:false; response_revision:16238; number_of_response:1; }","duration":"136.841352ms","start":"2026-10-09T05:56:27.81312Z","end":"2026-10-09T05:56:27.949961Z","steps":["trace[909596617] 'process raft request' (duration: 86.887155ms)","trace[909596617] 'compare' (duration: 49.631791ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:56:27.950253Z","caller":"traceutil/trace.go:171","msg":"trace[739827791] transaction","detail":"{read_only:false; response_revision:16240; number_of_response:1; }","duration":"136.845194ms","start":"2026-10-09T05:56:27.813397Z","end":"2026-10-09T05:56:27.950243Z","steps":["trace[739827791] 'process raft request' (duration: 136.517757ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:56:27.950498Z","caller":"traceutil/trace.go:171","msg":"trace[1768949398] transaction","detail":"{read_only:false; response_revision:16241; number_of_response:1; }","duration":"136.675579ms","start":"2026-10-09T05:56:27.813806Z","end":"2026-10-09T05:56:27.950481Z","steps":["trace[1768949398] 'process raft request' (duration: 136.396035ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:58:07.106049Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14549} {"level":"info","ts":"2026-10-09T05:58:07.192938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14549,"took":"83.466172ms","hash":1822592300,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":31264768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T05:58:07.193012Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1822592300,"revision":14549,"compact-revision":12546} {"level":"warn","ts":"2026-10-09T06:00:11.586579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.990603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T06:00:11.586663Z","caller":"traceutil/trace.go:171","msg":"trace[580238453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17992; }","duration":"119.096976ms","start":"2026-10-09T06:00:11.467547Z","end":"2026-10-09T06:00:11.586644Z","steps":["trace[580238453] 'agreement among raft nodes before linearized reading' (duration: 118.897112ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:11.586879Z","caller":"traceutil/trace.go:171","msg":"trace[1811685817] transaction","detail":"{read_only:false; response_revision:17992; number_of_response:1; }","duration":"119.384412ms","start":"2026-10-09T06:00:11.467453Z","end":"2026-10-09T06:00:11.586838Z","steps":["trace[1811685817] 'process raft request' (duration: 118.860291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:11.587133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.809172ms","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-10-09T06:00:11.587189Z","caller":"traceutil/trace.go:171","msg":"trace[294853297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17992; }","duration":"108.884503ms","start":"2026-10-09T06:00:11.478288Z","end":"2026-10-09T06:00:11.587172Z","steps":["trace[294853297] 'agreement among raft nodes before linearized reading' (duration: 108.759211ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:11.856961Z","caller":"traceutil/trace.go:171","msg":"trace[1048602688] transaction","detail":"{read_only:false; response_revision:17993; number_of_response:1; }","duration":"292.9578ms","start":"2026-10-09T06:00:11.563979Z","end":"2026-10-09T06:00:11.856936Z","steps":["trace[1048602688] 'process raft request' (duration: 292.782117ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:11.891769Z","caller":"traceutil/trace.go:171","msg":"trace[1569830043] transaction","detail":"{read_only:false; response_revision:17995; number_of_response:1; }","duration":"297.773601ms","start":"2026-10-09T06:00:11.59397Z","end":"2026-10-09T06:00:11.891744Z","steps":["trace[1569830043] 'process raft request' (duration: 297.677979ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:11.891846Z","caller":"traceutil/trace.go:171","msg":"trace[1436618317] transaction","detail":"{read_only:false; response_revision:17994; number_of_response:1; }","duration":"299.946943ms","start":"2026-10-09T06:00:11.591834Z","end":"2026-10-09T06:00:11.891781Z","steps":["trace[1436618317] 'process raft request' (duration: 299.678687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:11.892041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:00:11.591812Z","time spent":"300.130786ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:00:11.892141Z","caller":"traceutil/trace.go:171","msg":"trace[1521513638] transaction","detail":"{read_only:false; response_revision:17996; number_of_response:1; }","duration":"296.985706ms","start":"2026-10-09T06:00:11.595129Z","end":"2026-10-09T06:00:11.892115Z","steps":["trace[1521513638] 'process raft request' (duration: 296.571078ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:11.892468Z","caller":"traceutil/trace.go:171","msg":"trace[1072224203] linearizableReadLoop","detail":"{readStateIndex:18624; appliedIndex:18618; }","duration":"287.836123ms","start":"2026-10-09T06:00:11.604615Z","end":"2026-10-09T06:00:11.892451Z","steps":["trace[1072224203] 'read index received' (duration: 252.242669ms)","trace[1072224203] 'applied index is now lower than readState.Index' (duration: 35.592534ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:00:11.892531Z","caller":"traceutil/trace.go:171","msg":"trace[1881112541] transaction","detail":"{read_only:false; response_revision:17997; number_of_response:1; }","duration":"293.666313ms","start":"2026-10-09T06:00:11.598856Z","end":"2026-10-09T06:00:11.892523Z","steps":["trace[1881112541] 'process raft request' (duration: 293.107462ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:11.892703Z","caller":"traceutil/trace.go:171","msg":"trace[417634248] transaction","detail":"{read_only:false; response_revision:17998; number_of_response:1; }","duration":"292.866809ms","start":"2026-10-09T06:00:11.599815Z","end":"2026-10-09T06:00:11.892682Z","steps":["trace[417634248] 'process raft request' (duration: 292.523142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:11.892752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.121138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-2w29f\" ","response":"range_response_count:1 size:18655"} {"level":"info","ts":"2026-10-09T06:00:11.89282Z","caller":"traceutil/trace.go:171","msg":"trace[53713913] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-2w29f; range_end:; response_count:1; response_revision:17998; }","duration":"288.21002ms","start":"2026-10-09T06:00:11.604573Z","end":"2026-10-09T06:00:11.892783Z","steps":["trace[53713913] 'agreement among raft nodes before linearized reading' (duration: 288.059487ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:12.004943Z","caller":"traceutil/trace.go:171","msg":"trace[2144933126] linearizableReadLoop","detail":"{readStateIndex:18625; appliedIndex:18624; }","duration":"112.41849ms","start":"2026-10-09T06:00:11.892499Z","end":"2026-10-09T06:00:12.004917Z","steps":["trace[2144933126] 'read index received' (duration: 82.730367ms)","trace[2144933126] 'applied index is now lower than readState.Index' (duration: 29.686662ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:00:12.0052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.708601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-09T06:00:12.005242Z","caller":"traceutil/trace.go:171","msg":"trace[434411245] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17999; }","duration":"182.809154ms","start":"2026-10-09T06:00:11.822422Z","end":"2026-10-09T06:00:12.005231Z","steps":["trace[434411245] 'agreement among raft nodes before linearized reading' (duration: 182.63717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:12.005232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.886498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r9l59\" ","response":"range_response_count:1 size:6532"} {"level":"info","ts":"2026-10-09T06:00:12.005308Z","caller":"traceutil/trace.go:171","msg":"trace[1415302499] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r9l59; range_end:; response_count:1; response_revision:17999; }","duration":"133.00595ms","start":"2026-10-09T06:00:11.87228Z","end":"2026-10-09T06:00:12.005286Z","steps":["trace[1415302499] 'agreement among raft nodes before linearized reading' (duration: 132.728544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:12.005458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.110969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-5b587\" ","response":"range_response_count:1 size:18680"} {"level":"warn","ts":"2026-10-09T06:00:12.005531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.53055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-kcxdp\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-10-09T06:00:12.005547Z","caller":"traceutil/trace.go:171","msg":"trace[807199769] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-5b587; range_end:; response_count:1; response_revision:17999; }","duration":"400.223322ms","start":"2026-10-09T06:00:11.605302Z","end":"2026-10-09T06:00:12.005525Z","steps":["trace[807199769] 'agreement among raft nodes before linearized reading' (duration: 399.972867ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:12.00556Z","caller":"traceutil/trace.go:171","msg":"trace[1335092552] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-kcxdp; range_end:; response_count:1; response_revision:17999; }","duration":"379.560481ms","start":"2026-10-09T06:00:11.625991Z","end":"2026-10-09T06:00:12.005551Z","steps":["trace[1335092552] 'agreement among raft nodes before linearized reading' (duration: 379.471079ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:12.005609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:00:11.625942Z","time spent":"379.659063ms","remote":"127.0.0.1:35550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22157,"request content":"key:\"/registry/pods/openstack/nova-compute-default-kcxdp\" "} {"level":"warn","ts":"2026-10-09T06:00:12.005606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:00:11.605295Z","time spent":"400.297844ms","remote":"127.0.0.1:35550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":18704,"request content":"key:\"/registry/pods/openstack/neutron-l3-agent-default-5b587\" "} {"level":"info","ts":"2026-10-09T06:00:12.005503Z","caller":"traceutil/trace.go:171","msg":"trace[1953867215] transaction","detail":"{read_only:false; response_revision:17999; number_of_response:1; }","duration":"137.145218ms","start":"2026-10-09T06:00:11.868319Z","end":"2026-10-09T06:00:12.005464Z","steps":["trace[1953867215] 'process raft request' (duration: 106.988446ms)","trace[1953867215] 'compare' (duration: 29.448048ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:00:12.00649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.209213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:92 size:920819"} {"level":"info","ts":"2026-10-09T06:00:12.00656Z","caller":"traceutil/trace.go:171","msg":"trace[990416718] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:92; response_revision:17999; }","duration":"106.282474ms","start":"2026-10-09T06:00:11.900261Z","end":"2026-10-09T06:00:12.006543Z","steps":["trace[990416718] 'agreement among raft nodes before linearized reading' (duration: 105.071032ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:13.719735Z","caller":"traceutil/trace.go:171","msg":"trace[37006006] linearizableReadLoop","detail":"{readStateIndex:18632; appliedIndex:18631; }","duration":"126.635199ms","start":"2026-10-09T06:00:13.593075Z","end":"2026-10-09T06:00:13.71971Z","steps":["trace[37006006] 'read index received' (duration: 126.370414ms)","trace[37006006] 'applied index is now lower than readState.Index' (duration: 263.585µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:00:13.719994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.910144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:1 size:5311"} {"level":"info","ts":"2026-10-09T06:00:13.720045Z","caller":"traceutil/trace.go:171","msg":"trace[1623778621] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:1; response_revision:18006; }","duration":"127.015957ms","start":"2026-10-09T06:00:13.593013Z","end":"2026-10-09T06:00:13.720029Z","steps":["trace[1623778621] 'agreement among raft nodes before linearized reading' (duration: 126.860554ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:13.720016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.794283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:00:13.720105Z","caller":"traceutil/trace.go:171","msg":"trace[561064354] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:18006; }","duration":"125.910986ms","start":"2026-10-09T06:00:13.594172Z","end":"2026-10-09T06:00:13.720083Z","steps":["trace[561064354] 'agreement among raft nodes before linearized reading' (duration: 125.754893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:13.720096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.821784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:00:13.720183Z","caller":"traceutil/trace.go:171","msg":"trace[1202485357] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:18006; }","duration":"125.959146ms","start":"2026-10-09T06:00:13.594201Z","end":"2026-10-09T06:00:13.72016Z","steps":["trace[1202485357] 'agreement among raft nodes before linearized reading' (duration: 125.789463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:13.720016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.668441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:00:13.720241Z","caller":"traceutil/trace.go:171","msg":"trace[1252338909] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:18006; }","duration":"125.936406ms","start":"2026-10-09T06:00:13.594287Z","end":"2026-10-09T06:00:13.720224Z","steps":["trace[1252338909] 'agreement among raft nodes before linearized reading' (duration: 125.670361ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:13.719831Z","caller":"traceutil/trace.go:171","msg":"trace[1924915500] transaction","detail":"{read_only:false; response_revision:18006; number_of_response:1; }","duration":"157.153988ms","start":"2026-10-09T06:00:13.56262Z","end":"2026-10-09T06:00:13.719774Z","steps":["trace[1924915500] 'process raft request' (duration: 156.921973ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:02:51.544185Z","caller":"traceutil/trace.go:171","msg":"trace[2104103511] transaction","detail":"{read_only:false; response_revision:19053; number_of_response:1; }","duration":"121.48139ms","start":"2026-10-09T06:02:51.422672Z","end":"2026-10-09T06:02:51.544154Z","steps":["trace[2104103511] 'process raft request' (duration: 121.313906ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:03:07.112816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16881} {"level":"info","ts":"2026-10-09T06:03:07.219309Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16881,"took":"102.348445ms","hash":2985565241,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":32587776,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T06:03:07.219423Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2985565241,"revision":16881,"compact-revision":14549} {"level":"info","ts":"2026-10-09T06:03:14.477533Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b9fef886b531fede","local-member-applied-index":20006,"local-member-snapshot-index":10005,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T06:03:14.483435Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20006} {"level":"info","ts":"2026-10-09T06:03:14.483676Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15006} {"level":"info","ts":"2026-10-09T06:08:07.128757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19276} {"level":"info","ts":"2026-10-09T06:08:07.230948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19276,"took":"98.712425ms","hash":1274089376,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":34287616,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-09T06:08:07.231172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1274089376,"revision":19276,"compact-revision":16881} {"level":"info","ts":"2026-10-09T06:08:51.694622Z","caller":"traceutil/trace.go:171","msg":"trace[1173316799] transaction","detail":"{read_only:false; response_revision:21861; number_of_response:1; }","duration":"143.027741ms","start":"2026-10-09T06:08:51.551562Z","end":"2026-10-09T06:08:51.694589Z","steps":["trace[1173316799] 'process raft request' (duration: 142.834917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:08:52.590003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.812324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:08:52.590128Z","caller":"traceutil/trace.go:171","msg":"trace[1796276239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21863; }","duration":"193.003237ms","start":"2026-10-09T06:08:52.397097Z","end":"2026-10-09T06:08:52.5901Z","steps":["trace[1796276239] 'range keys from in-memory index tree' (duration: 192.704112ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:54.085834Z","caller":"traceutil/trace.go:171","msg":"trace[1184771703] transaction","detail":"{read_only:false; response_revision:24373; number_of_response:1; }","duration":"169.628826ms","start":"2026-10-09T06:12:53.916182Z","end":"2026-10-09T06:12:54.085811Z","steps":["trace[1184771703] 'process raft request' (duration: 169.448573ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:54.210973Z","caller":"traceutil/trace.go:171","msg":"trace[278916639] linearizableReadLoop","detail":"{readStateIndex:25204; appliedIndex:25202; }","duration":"192.912479ms","start":"2026-10-09T06:12:54.018038Z","end":"2026-10-09T06:12:54.21095Z","steps":["trace[278916639] 'read index received' (duration: 67.658143ms)","trace[278916639] 'applied index is now lower than readState.Index' (duration: 125.253356ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:12:54.211241Z","caller":"traceutil/trace.go:171","msg":"trace[1552704165] transaction","detail":"{read_only:false; response_revision:24374; number_of_response:1; }","duration":"247.888981ms","start":"2026-10-09T06:12:53.96333Z","end":"2026-10-09T06:12:54.211219Z","steps":["trace[1552704165] 'process raft request' (duration: 247.392092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:54.211431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.374858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-09T06:12:54.211496Z","caller":"traceutil/trace.go:171","msg":"trace[1287439936] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24375; }","duration":"193.50168ms","start":"2026-10-09T06:12:54.017981Z","end":"2026-10-09T06:12:54.211482Z","steps":["trace[1287439936] 'agreement among raft nodes before linearized reading' (duration: 193.194964ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:54.211371Z","caller":"traceutil/trace.go:171","msg":"trace[1730590108] transaction","detail":"{read_only:false; response_revision:24375; number_of_response:1; }","duration":"143.34541ms","start":"2026-10-09T06:12:54.06795Z","end":"2026-10-09T06:12:54.211295Z","steps":["trace[1730590108] 'process raft request' (duration: 142.951262ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:54.211548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.787512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:987"} {"level":"info","ts":"2026-10-09T06:12:54.211805Z","caller":"traceutil/trace.go:171","msg":"trace[1920273295] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:24375; }","duration":"140.118008ms","start":"2026-10-09T06:12:54.071647Z","end":"2026-10-09T06:12:54.211765Z","steps":["trace[1920273295] 'agreement among raft nodes before linearized reading' (duration: 139.606008ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:13:07.135753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21633} {"level":"info","ts":"2026-10-09T06:13:07.250608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21633,"took":"110.714629ms","hash":1463515375,"current-db-size-bytes":56127488,"current-db-size":"56 MB","current-db-size-in-use-bytes":42545152,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-09T06:13:07.250679Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1463515375,"revision":21633,"compact-revision":19276} {"level":"info","ts":"2026-10-09T06:14:49.253411Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006743.wal"} {"level":"info","ts":"2026-10-09T06:16:13.715413Z","caller":"traceutil/trace.go:171","msg":"trace[1741920552] transaction","detail":"{read_only:false; response_revision:26416; number_of_response:1; }","duration":"360.284073ms","start":"2026-10-09T06:16:13.355097Z","end":"2026-10-09T06:16:13.715381Z","steps":["trace[1741920552] 'process raft request' (duration: 360.093129ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:13.715428Z","caller":"traceutil/trace.go:171","msg":"trace[698306984] linearizableReadLoop","detail":"{readStateIndex:27299; appliedIndex:27299; }","duration":"316.036674ms","start":"2026-10-09T06:16:13.399355Z","end":"2026-10-09T06:16:13.715392Z","steps":["trace[698306984] 'read index received' (duration: 316.018613ms)","trace[698306984] 'applied index is now lower than readState.Index' (duration: 15.481µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:13.715696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:13.355068Z","time spent":"360.462746ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:13.715722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.341989ms","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-10-09T06:16:13.715792Z","caller":"traceutil/trace.go:171","msg":"trace[16847410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26416; }","duration":"316.439011ms","start":"2026-10-09T06:16:13.399343Z","end":"2026-10-09T06:16:13.715782Z","steps":["trace[16847410] 'agreement among raft nodes before linearized reading' (duration: 316.149696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:13.71583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:13.399297Z","time spent":"316.524433ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T06:16:13.73487Z","caller":"traceutil/trace.go:171","msg":"trace[329061276] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"166.05339ms","start":"2026-10-09T06:16:13.568795Z","end":"2026-10-09T06:16:13.734848Z","steps":["trace[329061276] 'process raft request' (duration: 165.478499ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:13.735096Z","caller":"traceutil/trace.go:171","msg":"trace[365104462] transaction","detail":"{read_only:false; response_revision:26418; number_of_response:1; }","duration":"153.469601ms","start":"2026-10-09T06:16:13.581617Z","end":"2026-10-09T06:16:13.735086Z","steps":["trace[365104462] 'process raft request' (duration: 152.808618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:13.735294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.803449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:13.73534Z","caller":"traceutil/trace.go:171","msg":"trace[1729303857] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:26418; }","duration":"103.907961ms","start":"2026-10-09T06:16:13.631419Z","end":"2026-10-09T06:16:13.735327Z","steps":["trace[1729303857] 'agreement among raft nodes before linearized reading' (duration: 103.82542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:13.735583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.374912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T06:16:13.735619Z","caller":"traceutil/trace.go:171","msg":"trace[314642892] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26418; }","duration":"131.438124ms","start":"2026-10-09T06:16:13.60417Z","end":"2026-10-09T06:16:13.735608Z","steps":["trace[314642892] 'agreement among raft nodes before linearized reading' (duration: 131.310951ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:13.735822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.885995ms","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-10-09T06:16:13.735893Z","caller":"traceutil/trace.go:171","msg":"trace[915548678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26418; }","duration":"158.001727ms","start":"2026-10-09T06:16:13.577874Z","end":"2026-10-09T06:16:13.735876Z","steps":["trace[915548678] 'agreement among raft nodes before linearized reading' (duration: 157.798833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:14.017663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.844949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:16:14.017883Z","caller":"traceutil/trace.go:171","msg":"trace[1853165990] transaction","detail":"{read_only:false; response_revision:26420; number_of_response:1; }","duration":"278.575615ms","start":"2026-10-09T06:16:13.739275Z","end":"2026-10-09T06:16:14.017851Z","steps":["trace[1853165990] 'process raft request' (duration: 76.42137ms)","trace[1853165990] 'compare' (duration: 201.682156ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:14.084267Z","caller":"traceutil/trace.go:171","msg":"trace[39515198] transaction","detail":"{read_only:false; response_revision:26421; number_of_response:1; }","duration":"344.458743ms","start":"2026-10-09T06:16:13.739772Z","end":"2026-10-09T06:16:14.08423Z","steps":["trace[39515198] 'process raft request' (duration: 344.250279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:14.08444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:13.73974Z","time spent":"344.612947ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:14.084637Z","caller":"traceutil/trace.go:171","msg":"trace[422287260] transaction","detail":"{read_only:false; response_revision:26422; number_of_response:1; }","duration":"340.889195ms","start":"2026-10-09T06:16:13.743703Z","end":"2026-10-09T06:16:14.084593Z","steps":["trace[422287260] 'process raft request' (duration: 340.471077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:14.084873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:13.743685Z","time spent":"341.040048ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:14.085368Z","caller":"traceutil/trace.go:171","msg":"trace[1371377110] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"294.987246ms","start":"2026-10-09T06:16:13.79036Z","end":"2026-10-09T06:16:14.085347Z","steps":["trace[1371377110] 'process raft request' (duration: 294.087739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:14.305786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.082581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:16:14.305982Z","caller":"traceutil/trace.go:171","msg":"trace[1561170630] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"416.493869ms","start":"2026-10-09T06:16:13.88941Z","end":"2026-10-09T06:16:14.305904Z","steps":["trace[1561170630] 'process raft request' (duration: 260.802237ms)","trace[1561170630] 'compare' (duration: 154.940518ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:14.306226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:13.889382Z","time spent":"416.695183ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:14.641942Z","caller":"traceutil/trace.go:171","msg":"trace[1820058064] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"100.923134ms","start":"2026-10-09T06:16:14.540992Z","end":"2026-10-09T06:16:14.641915Z","steps":["trace[1820058064] 'process raft request' (duration: 100.72404ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:14.834454Z","caller":"traceutil/trace.go:171","msg":"trace[1981955125] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"276.413503ms","start":"2026-10-09T06:16:14.558017Z","end":"2026-10-09T06:16:14.83443Z","steps":["trace[1981955125] 'process raft request' (duration: 241.249746ms)","trace[1981955125] 'compare' (duration: 34.900892ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:15.139804Z","caller":"traceutil/trace.go:171","msg":"trace[277646644] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"300.339297ms","start":"2026-10-09T06:16:14.839412Z","end":"2026-10-09T06:16:15.139752Z","steps":["trace[277646644] 'process raft request' (duration: 300.170454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:15.140017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:14.839384Z","time spent":"300.497839ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:15.213596Z","caller":"traceutil/trace.go:171","msg":"trace[317767422] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"211.233377ms","start":"2026-10-09T06:16:15.002337Z","end":"2026-10-09T06:16:15.21357Z","steps":["trace[317767422] 'process raft request' (duration: 211.025213ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:15.213808Z","caller":"traceutil/trace.go:171","msg":"trace[697215852] transaction","detail":"{read_only:false; response_revision:26429; number_of_response:1; }","duration":"155.282966ms","start":"2026-10-09T06:16:15.058504Z","end":"2026-10-09T06:16:15.213787Z","steps":["trace[697215852] 'process raft request' (duration: 155.019471ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:15.344153Z","caller":"traceutil/trace.go:171","msg":"trace[1531434993] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"187.996336ms","start":"2026-10-09T06:16:15.156119Z","end":"2026-10-09T06:16:15.344115Z","steps":["trace[1531434993] 'process raft request' (duration: 166.707233ms)","trace[1531434993] 'compare' (duration: 21.108909ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:20.658922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.250317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:16:20.658951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.868982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.187\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-09T06:16:20.659015Z","caller":"traceutil/trace.go:171","msg":"trace[233067540] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:26473; }","duration":"162.3903ms","start":"2026-10-09T06:16:20.496601Z","end":"2026-10-09T06:16:20.658991Z","steps":["trace[233067540] 'count revisions from in-memory index tree' (duration: 162.149556ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:20.659045Z","caller":"traceutil/trace.go:171","msg":"trace[1731520787] range","detail":"{range_begin:/registry/masterleases/199.204.45.187; range_end:; response_count:1; response_revision:26473; }","duration":"199.001075ms","start":"2026-10-09T06:16:20.460025Z","end":"2026-10-09T06:16:20.659026Z","steps":["trace[1731520787] 'range keys from in-memory index tree' (duration: 198.731119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:21.034784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.368299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:16:21.035014Z","caller":"traceutil/trace.go:171","msg":"trace[1383844956] linearizableReadLoop","detail":"{readStateIndex:27359; appliedIndex:27357; }","duration":"218.692978ms","start":"2026-10-09T06:16:20.816299Z","end":"2026-10-09T06:16:21.034992Z","steps":["trace[1383844956] 'read index received' (duration: 19.292597ms)","trace[1383844956] 'applied index is now lower than readState.Index' (duration: 199.398851ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:21.035028Z","caller":"traceutil/trace.go:171","msg":"trace[740316553] transaction","detail":"{read_only:false; response_revision:26474; number_of_response:1; }","duration":"372.988033ms","start":"2026-10-09T06:16:20.661983Z","end":"2026-10-09T06:16:21.034971Z","steps":["trace[740316553] 'process raft request' (duration: 210.299838ms)","trace[740316553] 'compare' (duration: 162.197796ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:21.035229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:20.661959Z","time spent":"373.176988ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:21.035253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.938383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:21.035369Z","caller":"traceutil/trace.go:171","msg":"trace[1766278142] transaction","detail":"{read_only:false; response_revision:26476; number_of_response:1; }","duration":"182.424741ms","start":"2026-10-09T06:16:20.852919Z","end":"2026-10-09T06:16:21.035344Z","steps":["trace[1766278142] 'process raft request' (duration: 182.261957ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:21.035383Z","caller":"traceutil/trace.go:171","msg":"trace[1656847530] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:26475; }","duration":"219.119256ms","start":"2026-10-09T06:16:20.816248Z","end":"2026-10-09T06:16:21.035368Z","steps":["trace[1656847530] 'agreement among raft nodes before linearized reading' (duration: 218.82339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:21.035498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.044228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-09T06:16:21.0353Z","caller":"traceutil/trace.go:171","msg":"trace[1945318728] transaction","detail":"{read_only:false; response_revision:26475; number_of_response:1; }","duration":"198.402333ms","start":"2026-10-09T06:16:20.836872Z","end":"2026-10-09T06:16:21.035274Z","steps":["trace[1945318728] 'process raft request' (duration: 198.035616ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:21.035569Z","caller":"traceutil/trace.go:171","msg":"trace[1110156686] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26476; }","duration":"128.160461ms","start":"2026-10-09T06:16:20.907392Z","end":"2026-10-09T06:16:21.035552Z","steps":["trace[1110156686] 'agreement among raft nodes before linearized reading' (duration: 127.911226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:21.299819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.846145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T06:16:21.29997Z","caller":"traceutil/trace.go:171","msg":"trace[1707310085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26476; }","duration":"138.025998ms","start":"2026-10-09T06:16:21.161926Z","end":"2026-10-09T06:16:21.299952Z","steps":["trace[1707310085] 'range keys from in-memory index tree' (duration: 137.682742ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:21.481395Z","caller":"traceutil/trace.go:171","msg":"trace[644277503] transaction","detail":"{read_only:false; response_revision:26477; number_of_response:1; }","duration":"250.592903ms","start":"2026-10-09T06:16:21.230779Z","end":"2026-10-09T06:16:21.481372Z","steps":["trace[644277503] 'process raft request' (duration: 250.43008ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:21.746042Z","caller":"traceutil/trace.go:171","msg":"trace[1833072473] linearizableReadLoop","detail":"{readStateIndex:27363; appliedIndex:27361; }","duration":"479.120587ms","start":"2026-10-09T06:16:21.266902Z","end":"2026-10-09T06:16:21.746023Z","steps":["trace[1833072473] 'read index received' (duration: 214.313605ms)","trace[1833072473] 'applied index is now lower than readState.Index' (duration: 264.806022ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:21.746174Z","caller":"traceutil/trace.go:171","msg":"trace[139081811] transaction","detail":"{read_only:false; response_revision:26479; number_of_response:1; }","duration":"441.923592ms","start":"2026-10-09T06:16:21.30422Z","end":"2026-10-09T06:16:21.746143Z","steps":["trace[139081811] 'process raft request' (duration: 441.760019ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:21.746215Z","caller":"traceutil/trace.go:171","msg":"trace[227543152] transaction","detail":"{read_only:false; response_revision:26478; number_of_response:1; }","duration":"513.693192ms","start":"2026-10-09T06:16:21.232501Z","end":"2026-10-09T06:16:21.746194Z","steps":["trace[227543152] 'process raft request' (duration: 439.22834ms)","trace[227543152] 'compare' (duration: 73.864891ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:21.746234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.309551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:21.746269Z","caller":"traceutil/trace.go:171","msg":"trace[1940783458] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26479; }","duration":"479.381512ms","start":"2026-10-09T06:16:21.266876Z","end":"2026-10-09T06:16:21.746257Z","steps":["trace[1940783458] 'agreement among raft nodes before linearized reading' (duration: 479.307971ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:21.746304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:21.266866Z","time spent":"479.430353ms","remote":"127.0.0.1:54328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-10-09T06:16:21.746349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:21.232483Z","time spent":"513.777614ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:21.746349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:21.304198Z","time spent":"442.048264ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:21.758603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.278218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:16:21.758653Z","caller":"traceutil/trace.go:171","msg":"trace[1794900692] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:26480; }","duration":"181.3709ms","start":"2026-10-09T06:16:21.57727Z","end":"2026-10-09T06:16:21.75864Z","steps":["trace[1794900692] 'agreement among raft nodes before linearized reading' (duration: 181.161496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:21.758872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.715821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:21.758905Z","caller":"traceutil/trace.go:171","msg":"trace[407825172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26480; }","duration":"361.784932ms","start":"2026-10-09T06:16:21.397111Z","end":"2026-10-09T06:16:21.758896Z","steps":["trace[407825172] 'agreement among raft nodes before linearized reading' (duration: 361.735111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:21.75894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:21.397092Z","time spent":"361.835464ms","remote":"127.0.0.1:35366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T06:16:21.898599Z","caller":"traceutil/trace.go:171","msg":"trace[436484373] linearizableReadLoop","detail":"{readStateIndex:27366; appliedIndex:27365; }","duration":"130.387134ms","start":"2026-10-09T06:16:21.768187Z","end":"2026-10-09T06:16:21.898574Z","steps":["trace[436484373] 'read index received' (duration: 124.754687ms)","trace[436484373] 'applied index is now lower than readState.Index' (duration: 5.631017ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:21.89864Z","caller":"traceutil/trace.go:171","msg":"trace[345502008] transaction","detail":"{read_only:false; response_revision:26481; number_of_response:1; }","duration":"131.814511ms","start":"2026-10-09T06:16:21.766789Z","end":"2026-10-09T06:16:21.898603Z","steps":["trace[345502008] 'process raft request' (duration: 126.138333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:21.898794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.535767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:21.898949Z","caller":"traceutil/trace.go:171","msg":"trace[2114871729] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26481; }","duration":"130.793602ms","start":"2026-10-09T06:16:21.768133Z","end":"2026-10-09T06:16:21.898927Z","steps":["trace[2114871729] 'agreement among raft nodes before linearized reading' (duration: 130.531297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:22.146826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.354252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:16:22.146999Z","caller":"traceutil/trace.go:171","msg":"trace[1222215532] linearizableReadLoop","detail":"{readStateIndex:27367; appliedIndex:27366; }","duration":"248.323569ms","start":"2026-10-09T06:16:21.898659Z","end":"2026-10-09T06:16:22.146983Z","steps":["trace[1222215532] 'read index received' (duration: 136.265474ms)","trace[1222215532] 'applied index is now lower than readState.Index' (duration: 112.056305ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:22.147212Z","caller":"traceutil/trace.go:171","msg":"trace[701052767] transaction","detail":"{read_only:false; response_revision:26482; number_of_response:1; }","duration":"367.052542ms","start":"2026-10-09T06:16:21.780035Z","end":"2026-10-09T06:16:22.147088Z","steps":["trace[701052767] 'process raft request' (duration: 255.054588ms)","trace[701052767] 'compare' (duration: 111.26486ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:22.147329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:21.779997Z","time spent":"367.294536ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:22.147467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.036434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-10-09T06:16:22.147542Z","caller":"traceutil/trace.go:171","msg":"trace[1636539496] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:26482; }","duration":"103.121566ms","start":"2026-10-09T06:16:22.044402Z","end":"2026-10-09T06:16:22.147524Z","steps":["trace[1636539496] 'agreement among raft nodes before linearized reading' (duration: 102.903392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:22.147777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.413466ms","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-10-09T06:16:22.147881Z","caller":"traceutil/trace.go:171","msg":"trace[286990198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26482; }","duration":"360.554778ms","start":"2026-10-09T06:16:21.78729Z","end":"2026-10-09T06:16:22.147844Z","steps":["trace[286990198] 'agreement among raft nodes before linearized reading' (duration: 360.315453ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:22.14793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:21.787277Z","time spent":"360.640369ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T06:16:22.396504Z","caller":"traceutil/trace.go:171","msg":"trace[1599238236] transaction","detail":"{read_only:false; response_revision:26484; number_of_response:1; }","duration":"244.412016ms","start":"2026-10-09T06:16:22.152065Z","end":"2026-10-09T06:16:22.396477Z","steps":["trace[1599238236] 'process raft request' (duration: 242.750495ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:22.396725Z","caller":"traceutil/trace.go:171","msg":"trace[230591191] transaction","detail":"{read_only:false; response_revision:26486; number_of_response:1; }","duration":"244.028018ms","start":"2026-10-09T06:16:22.152683Z","end":"2026-10-09T06:16:22.396711Z","steps":["trace[230591191] 'process raft request' (duration: 243.775683ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:22.396811Z","caller":"traceutil/trace.go:171","msg":"trace[2094760625] transaction","detail":"{read_only:false; response_revision:26485; number_of_response:1; }","duration":"244.513488ms","start":"2026-10-09T06:16:22.152219Z","end":"2026-10-09T06:16:22.396732Z","steps":["trace[2094760625] 'process raft request' (duration: 244.169101ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:23.236439Z","caller":"traceutil/trace.go:171","msg":"trace[1293770066] transaction","detail":"{read_only:false; response_revision:26488; number_of_response:1; }","duration":"195.535749ms","start":"2026-10-09T06:16:23.040878Z","end":"2026-10-09T06:16:23.236413Z","steps":["trace[1293770066] 'process raft request' (duration: 195.376266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:23.413279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.602729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:16:23.413364Z","caller":"traceutil/trace.go:171","msg":"trace[634305785] linearizableReadLoop","detail":"{readStateIndex:27374; appliedIndex:27372; }","duration":"213.056821ms","start":"2026-10-09T06:16:23.200291Z","end":"2026-10-09T06:16:23.413348Z","steps":["trace[634305785] 'read index received' (duration: 35.879711ms)","trace[634305785] 'applied index is now lower than readState.Index' (duration: 177.17574ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:23.413441Z","caller":"traceutil/trace.go:171","msg":"trace[1485070188] transaction","detail":"{read_only:false; response_revision:26489; number_of_response:1; }","duration":"371.727521ms","start":"2026-10-09T06:16:23.041689Z","end":"2026-10-09T06:16:23.413417Z","steps":["trace[1485070188] 'process raft request' (duration: 222.874207ms)","trace[1485070188] 'compare' (duration: 148.400135ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:23.413529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:23.041664Z","time spent":"371.820102ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:23.41346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.162973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:23.413663Z","caller":"traceutil/trace.go:171","msg":"trace[697367744] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:26489; }","duration":"213.380208ms","start":"2026-10-09T06:16:23.200249Z","end":"2026-10-09T06:16:23.41363Z","steps":["trace[697367744] 'agreement among raft nodes before linearized reading' (duration: 213.168824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:23.751598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.647892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:16:23.751859Z","caller":"traceutil/trace.go:171","msg":"trace[210983612] transaction","detail":"{read_only:false; response_revision:26490; number_of_response:1; }","duration":"264.976956ms","start":"2026-10-09T06:16:23.486848Z","end":"2026-10-09T06:16:23.751825Z","steps":["trace[210983612] 'process raft request' (duration: 118.011178ms)","trace[210983612] 'compare' (duration: 146.51823ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:24.171698Z","caller":"traceutil/trace.go:171","msg":"trace[500593432] transaction","detail":"{read_only:false; response_revision:26492; number_of_response:1; }","duration":"415.347658ms","start":"2026-10-09T06:16:23.756321Z","end":"2026-10-09T06:16:24.171669Z","steps":["trace[500593432] 'process raft request' (duration: 415.063663ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:24.171811Z","caller":"traceutil/trace.go:171","msg":"trace[1159461354] transaction","detail":"{read_only:false; response_revision:26493; number_of_response:1; }","duration":"407.975528ms","start":"2026-10-09T06:16:23.763806Z","end":"2026-10-09T06:16:24.171781Z","steps":["trace[1159461354] 'process raft request' (duration: 407.721533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:24.171895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:23.756291Z","time spent":"415.521361ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:24.171957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:23.763781Z","time spent":"408.09457ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:24.230833Z","caller":"traceutil/trace.go:171","msg":"trace[1369839198] transaction","detail":"{read_only:false; response_revision:26496; number_of_response:1; }","duration":"127.934068ms","start":"2026-10-09T06:16:24.102881Z","end":"2026-10-09T06:16:24.230816Z","steps":["trace[1369839198] 'process raft request' (duration: 127.844026ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:24.230981Z","caller":"traceutil/trace.go:171","msg":"trace[1236384053] transaction","detail":"{read_only:false; response_revision:26494; number_of_response:1; }","duration":"325.886522ms","start":"2026-10-09T06:16:23.905085Z","end":"2026-10-09T06:16:24.230972Z","steps":["trace[1236384053] 'process raft request' (duration: 325.468933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:24.231069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:23.905064Z","time spent":"325.942203ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:24.231171Z","caller":"traceutil/trace.go:171","msg":"trace[882560055] transaction","detail":"{read_only:false; response_revision:26495; number_of_response:1; }","duration":"321.010379ms","start":"2026-10-09T06:16:23.910143Z","end":"2026-10-09T06:16:24.231153Z","steps":["trace[882560055] 'process raft request' (duration: 320.54134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:24.231251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:23.910119Z","time spent":"321.089261ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:24.380114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.245617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-09T06:16:24.380236Z","caller":"traceutil/trace.go:171","msg":"trace[776009790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26497; }","duration":"134.395419ms","start":"2026-10-09T06:16:24.245794Z","end":"2026-10-09T06:16:24.38019Z","steps":["trace[776009790] 'range keys from in-memory index tree' (duration: 134.073343ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:24.539034Z","caller":"traceutil/trace.go:171","msg":"trace[1318601254] linearizableReadLoop","detail":"{readStateIndex:27383; appliedIndex:27382; }","duration":"138.424556ms","start":"2026-10-09T06:16:24.400587Z","end":"2026-10-09T06:16:24.539011Z","steps":["trace[1318601254] 'read index received' (duration: 138.272953ms)","trace[1318601254] 'applied index is now lower than readState.Index' (duration: 150.493µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:24.539212Z","caller":"traceutil/trace.go:171","msg":"trace[1648621009] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"154.399729ms","start":"2026-10-09T06:16:24.384799Z","end":"2026-10-09T06:16:24.539198Z","steps":["trace[1648621009] 'process raft request' (duration: 154.066352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:24.539518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.903334ms","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-10-09T06:16:24.539626Z","caller":"traceutil/trace.go:171","msg":"trace[912253408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26498; }","duration":"139.065508ms","start":"2026-10-09T06:16:24.400542Z","end":"2026-10-09T06:16:24.539607Z","steps":["trace[912253408] 'agreement among raft nodes before linearized reading' (duration: 138.737942ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:24.590331Z","caller":"traceutil/trace.go:171","msg":"trace[3980196] transaction","detail":"{read_only:false; response_revision:26499; number_of_response:1; }","duration":"188.617717ms","start":"2026-10-09T06:16:24.401683Z","end":"2026-10-09T06:16:24.590301Z","steps":["trace[3980196] 'process raft request' (duration: 187.281352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:24.590374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.409032ms","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-10-09T06:16:24.590427Z","caller":"traceutil/trace.go:171","msg":"trace[1239444423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26499; }","duration":"189.506574ms","start":"2026-10-09T06:16:24.400905Z","end":"2026-10-09T06:16:24.590412Z","steps":["trace[1239444423] 'agreement among raft nodes before linearized reading' (duration: 189.30694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:24.590586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.529415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-09T06:16:24.59069Z","caller":"traceutil/trace.go:171","msg":"trace[502086514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26499; }","duration":"189.686487ms","start":"2026-10-09T06:16:24.400981Z","end":"2026-10-09T06:16:24.590668Z","steps":["trace[502086514] 'agreement among raft nodes before linearized reading' (duration: 189.406162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:24.918591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.792646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:16:24.918812Z","caller":"traceutil/trace.go:171","msg":"trace[107191320] linearizableReadLoop","detail":"{readStateIndex:27387; appliedIndex:27385; }","duration":"203.796775ms","start":"2026-10-09T06:16:24.715Z","end":"2026-10-09T06:16:24.918797Z","steps":["trace[107191320] 'read index received' (duration: 39.809835ms)","trace[107191320] 'applied index is now lower than readState.Index' (duration: 163.98605ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:24.918894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.893427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:16:24.918881Z","caller":"traceutil/trace.go:171","msg":"trace[1835419315] transaction","detail":"{read_only:false; response_revision:26502; number_of_response:1; }","duration":"320.858735ms","start":"2026-10-09T06:16:24.597981Z","end":"2026-10-09T06:16:24.91884Z","steps":["trace[1835419315] 'process raft request' (duration: 320.698052ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:24.918927Z","caller":"traceutil/trace.go:171","msg":"trace[602509848] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:26502; }","duration":"203.969838ms","start":"2026-10-09T06:16:24.714946Z","end":"2026-10-09T06:16:24.918916Z","steps":["trace[602509848] 'agreement among raft nodes before linearized reading' (duration: 203.899107ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:24.918815Z","caller":"traceutil/trace.go:171","msg":"trace[1561055102] transaction","detail":"{read_only:false; response_revision:26501; number_of_response:1; }","duration":"322.786923ms","start":"2026-10-09T06:16:24.595991Z","end":"2026-10-09T06:16:24.918778Z","steps":["trace[1561055102] 'process raft request' (duration: 158.721061ms)","trace[1561055102] 'compare' (duration: 163.191605ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:24.919035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:24.597954Z","time spent":"320.994248ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:24.919154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:24.595965Z","time spent":"323.070238ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:25.663461Z","caller":"traceutil/trace.go:171","msg":"trace[215427146] linearizableReadLoop","detail":"{readStateIndex:27389; appliedIndex:27388; }","duration":"268.013703ms","start":"2026-10-09T06:16:25.395426Z","end":"2026-10-09T06:16:25.663439Z","steps":["trace[215427146] 'read index received' (duration: 267.916751ms)","trace[215427146] 'applied index is now lower than readState.Index' (duration: 95.882µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:25.663537Z","caller":"traceutil/trace.go:171","msg":"trace[2096126821] transaction","detail":"{read_only:false; response_revision:26504; number_of_response:1; }","duration":"297.621575ms","start":"2026-10-09T06:16:25.36588Z","end":"2026-10-09T06:16:25.663502Z","steps":["trace[2096126821] 'process raft request' (duration: 297.423241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:25.663623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.171466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:25.663706Z","caller":"traceutil/trace.go:171","msg":"trace[593896746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26504; }","duration":"268.303189ms","start":"2026-10-09T06:16:25.395382Z","end":"2026-10-09T06:16:25.663685Z","steps":["trace[593896746] 'agreement among raft nodes before linearized reading' (duration: 268.148716ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:25.82068Z","caller":"traceutil/trace.go:171","msg":"trace[170708371] linearizableReadLoop","detail":"{readStateIndex:27390; appliedIndex:27389; }","duration":"155.800185ms","start":"2026-10-09T06:16:25.664853Z","end":"2026-10-09T06:16:25.820653Z","steps":["trace[170708371] 'read index received' (duration: 152.779348ms)","trace[170708371] 'applied index is now lower than readState.Index' (duration: 3.019577ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:25.820782Z","caller":"traceutil/trace.go:171","msg":"trace[1643095861] transaction","detail":"{read_only:false; response_revision:26505; number_of_response:1; }","duration":"402.168899ms","start":"2026-10-09T06:16:25.418569Z","end":"2026-10-09T06:16:25.820738Z","steps":["trace[1643095861] 'process raft request' (duration: 399.1012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:25.820866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.001769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:25.820903Z","caller":"traceutil/trace.go:171","msg":"trace[9152968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26505; }","duration":"156.094131ms","start":"2026-10-09T06:16:25.664799Z","end":"2026-10-09T06:16:25.820893Z","steps":["trace[9152968] 'agreement among raft nodes before linearized reading' (duration: 155.940888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:25.820935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:25.418545Z","time spent":"402.306101ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:32.673004Z","caller":"traceutil/trace.go:171","msg":"trace[903125515] linearizableReadLoop","detail":"{readStateIndex:27440; appliedIndex:27439; }","duration":"468.039347ms","start":"2026-10-09T06:16:32.204942Z","end":"2026-10-09T06:16:32.672981Z","steps":["trace[903125515] 'read index received' (duration: 467.950856ms)","trace[903125515] 'applied index is now lower than readState.Index' (duration: 87.421µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:32.673149Z","caller":"traceutil/trace.go:171","msg":"trace[2066101159] transaction","detail":"{read_only:false; response_revision:26553; number_of_response:1; }","duration":"475.759104ms","start":"2026-10-09T06:16:32.197357Z","end":"2026-10-09T06:16:32.673116Z","steps":["trace[2066101159] 'process raft request' (duration: 475.485969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:32.673225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.273463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T06:16:32.673287Z","caller":"traceutil/trace.go:171","msg":"trace[71722548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26553; }","duration":"468.387164ms","start":"2026-10-09T06:16:32.20489Z","end":"2026-10-09T06:16:32.673277Z","steps":["trace[71722548] 'agreement among raft nodes before linearized reading' (duration: 468.208281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:32.673303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.197338Z","time spent":"475.885276ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:32.673324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.204873Z","time spent":"468.442716ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-09T06:16:32.967712Z","caller":"traceutil/trace.go:171","msg":"trace[1023435133] linearizableReadLoop","detail":"{readStateIndex:27443; appliedIndex:27440; }","duration":"294.614248ms","start":"2026-10-09T06:16:32.673069Z","end":"2026-10-09T06:16:32.967684Z","steps":["trace[1023435133] 'read index received' (duration: 272.383217ms)","trace[1023435133] 'applied index is now lower than readState.Index' (duration: 22.229341ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:32.967769Z","caller":"traceutil/trace.go:171","msg":"trace[619991076] transaction","detail":"{read_only:false; response_revision:26554; number_of_response:1; }","duration":"701.16085ms","start":"2026-10-09T06:16:32.266483Z","end":"2026-10-09T06:16:32.967644Z","steps":["trace[619991076] 'process raft request' (duration: 678.968229ms)","trace[619991076] 'compare' (duration: 21.791303ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:32.967939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.266469Z","time spent":"701.386504ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:32.968166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.469309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:32.968159Z","caller":"traceutil/trace.go:171","msg":"trace[646651596] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"699.441047ms","start":"2026-10-09T06:16:32.268682Z","end":"2026-10-09T06:16:32.968123Z","steps":["trace[646651596] 'process raft request' (duration: 698.851335ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:32.968221Z","caller":"traceutil/trace.go:171","msg":"trace[287815924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26556; }","duration":"571.554691ms","start":"2026-10-09T06:16:32.396653Z","end":"2026-10-09T06:16:32.968208Z","steps":["trace[287815924] 'agreement among raft nodes before linearized reading' (duration: 571.428239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:32.968253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.396644Z","time spent":"571.600952ms","remote":"127.0.0.1:35364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T06:16:32.968228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.550352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:32.968235Z","caller":"traceutil/trace.go:171","msg":"trace[778194220] transaction","detail":"{read_only:false; response_revision:26556; number_of_response:1; }","duration":"346.492792ms","start":"2026-10-09T06:16:32.621684Z","end":"2026-10-09T06:16:32.968176Z","steps":["trace[778194220] 'process raft request' (duration: 345.941971ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:32.968313Z","caller":"traceutil/trace.go:171","msg":"trace[1708282168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26556; }","duration":"599.692775ms","start":"2026-10-09T06:16:32.368599Z","end":"2026-10-09T06:16:32.968291Z","steps":["trace[1708282168] 'agreement among raft nodes before linearized reading' (duration: 599.501541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:32.968321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.268669Z","time spent":"699.559069ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:32.968376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.399894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-09T06:16:32.968395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.368587Z","time spent":"599.764366ms","remote":"127.0.0.1:35366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T06:16:32.968428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.621657Z","time spent":"346.685085ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:32.968458Z","caller":"traceutil/trace.go:171","msg":"trace[672171832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26556; }","duration":"391.530876ms","start":"2026-10-09T06:16:32.576906Z","end":"2026-10-09T06:16:32.968436Z","steps":["trace[672171832] 'agreement among raft nodes before linearized reading' (duration: 391.24943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:32.968276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.552422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-09T06:16:32.9685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.576886Z","time spent":"391.601508ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-09T06:16:32.968555Z","caller":"traceutil/trace.go:171","msg":"trace[1074363843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26556; }","duration":"327.831818ms","start":"2026-10-09T06:16:32.640679Z","end":"2026-10-09T06:16:32.968511Z","steps":["trace[1074363843] 'agreement among raft nodes before linearized reading' (duration: 327.38004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:32.968676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.640662Z","time spent":"327.964151ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T06:16:33.425314Z","caller":"traceutil/trace.go:171","msg":"trace[1124605567] linearizableReadLoop","detail":"{readStateIndex:27444; appliedIndex:27443; }","duration":"457.470526ms","start":"2026-10-09T06:16:32.967813Z","end":"2026-10-09T06:16:33.425284Z","steps":["trace[1124605567] 'read index received' (duration: 453.899669ms)","trace[1124605567] 'applied index is now lower than readState.Index' (duration: 3.569317ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:33.425369Z","caller":"traceutil/trace.go:171","msg":"trace[154313888] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"747.864924ms","start":"2026-10-09T06:16:32.677474Z","end":"2026-10-09T06:16:33.425339Z","steps":["trace[154313888] 'process raft request' (duration: 744.270407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:33.425513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.677446Z","time spent":"747.979376ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:33.425655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.547503ms","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-10-09T06:16:33.425696Z","caller":"traceutil/trace.go:171","msg":"trace[1618258960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26557; }","duration":"472.624884ms","start":"2026-10-09T06:16:32.953058Z","end":"2026-10-09T06:16:33.425683Z","steps":["trace[1618258960] 'agreement among raft nodes before linearized reading' (duration: 472.380169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:33.425735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.953047Z","time spent":"472.678056ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T06:16:33.425995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.364916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T06:16:33.426035Z","caller":"traceutil/trace.go:171","msg":"trace[337285621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26557; }","duration":"476.400216ms","start":"2026-10-09T06:16:32.94962Z","end":"2026-10-09T06:16:33.42602Z","steps":["trace[337285621] 'agreement among raft nodes before linearized reading' (duration: 476.275594ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:33.42606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.94955Z","time spent":"476.504048ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T06:16:33.426056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.924521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:33.426099Z","caller":"traceutil/trace.go:171","msg":"trace[633587381] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:26557; }","duration":"569.007813ms","start":"2026-10-09T06:16:32.857078Z","end":"2026-10-09T06:16:33.426086Z","steps":["trace[633587381] 'agreement among raft nodes before linearized reading' (duration: 568.930621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:33.426135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.85706Z","time spent":"569.064133ms","remote":"127.0.0.1:58490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"info","ts":"2026-10-09T06:16:33.453141Z","caller":"traceutil/trace.go:171","msg":"trace[109429985] transaction","detail":"{read_only:false; response_revision:26558; number_of_response:1; }","duration":"480.422223ms","start":"2026-10-09T06:16:32.972693Z","end":"2026-10-09T06:16:33.453116Z","steps":["trace[109429985] 'process raft request' (duration: 480.081826ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:33.453251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.972659Z","time spent":"480.536356ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:33.453528Z","caller":"traceutil/trace.go:171","msg":"trace[79905883] transaction","detail":"{read_only:false; response_revision:26559; number_of_response:1; }","duration":"480.421872ms","start":"2026-10-09T06:16:32.973094Z","end":"2026-10-09T06:16:33.453516Z","steps":["trace[79905883] 'process raft request' (duration: 479.935083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:33.453595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:32.973072Z","time spent":"480.491064ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:33.453683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.439709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-09T06:16:33.453794Z","caller":"traceutil/trace.go:171","msg":"trace[491113276] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:26560; }","duration":"445.534681ms","start":"2026-10-09T06:16:33.008209Z","end":"2026-10-09T06:16:33.453744Z","steps":["trace[491113276] 'agreement among raft nodes before linearized reading' (duration: 445.309057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:33.453851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:33.008189Z","time spent":"445.650683ms","remote":"127.0.0.1:35524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1890,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-09T06:16:33.454111Z","caller":"traceutil/trace.go:171","msg":"trace[1440738893] transaction","detail":"{read_only:false; response_revision:26560; number_of_response:1; }","duration":"128.953826ms","start":"2026-10-09T06:16:33.325142Z","end":"2026-10-09T06:16:33.454096Z","steps":["trace[1440738893] 'process raft request' (duration: 128.1336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:33.454256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.98901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:33.454338Z","caller":"traceutil/trace.go:171","msg":"trace[1709093942] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26560; }","duration":"222.082932ms","start":"2026-10-09T06:16:33.232236Z","end":"2026-10-09T06:16:33.454319Z","steps":["trace[1709093942] 'agreement among raft nodes before linearized reading' (duration: 221.94672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:33.98008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.495739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:16:33.98025Z","caller":"traceutil/trace.go:171","msg":"trace[287102881] linearizableReadLoop","detail":"{readStateIndex:27449; appliedIndex:27447; }","duration":"523.55649ms","start":"2026-10-09T06:16:33.456678Z","end":"2026-10-09T06:16:33.980234Z","steps":["trace[287102881] 'read index received' (duration: 242.763285ms)","trace[287102881] 'applied index is now lower than readState.Index' (duration: 280.791835ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:33.980727Z","caller":"traceutil/trace.go:171","msg":"trace[491370049] transaction","detail":"{read_only:false; response_revision:26562; number_of_response:1; }","duration":"547.041056ms","start":"2026-10-09T06:16:33.433661Z","end":"2026-10-09T06:16:33.980702Z","steps":["trace[491370049] 'process raft request' (duration: 546.508536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:33.980828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:33.433636Z","time spent":"547.152148ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:33.980837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.133381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:33.98091Z","caller":"traceutil/trace.go:171","msg":"trace[1014904017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26562; }","duration":"524.250394ms","start":"2026-10-09T06:16:33.45664Z","end":"2026-10-09T06:16:33.98089Z","steps":["trace[1014904017] 'agreement among raft nodes before linearized reading' (duration: 524.086871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:33.980953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:33.456627Z","time spent":"524.314575ms","remote":"127.0.0.1:35364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T06:16:33.981021Z","caller":"traceutil/trace.go:171","msg":"trace[1350175326] transaction","detail":"{read_only:false; response_revision:26561; number_of_response:1; }","duration":"550.508861ms","start":"2026-10-09T06:16:33.4305Z","end":"2026-10-09T06:16:33.981009Z","steps":["trace[1350175326] 'process raft request' (duration: 269.002672ms)","trace[1350175326] 'compare' (duration: 279.508832ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:33.981075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:33.43048Z","time spent":"550.564833ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:34.275871Z","caller":"traceutil/trace.go:171","msg":"trace[1494552840] transaction","detail":"{read_only:false; response_revision:26563; number_of_response:1; }","duration":"427.617452ms","start":"2026-10-09T06:16:33.848227Z","end":"2026-10-09T06:16:34.275844Z","steps":["trace[1494552840] 'process raft request' (duration: 427.450878ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:34.276032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:33.848202Z","time spent":"427.754734ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:34.276887Z","caller":"traceutil/trace.go:171","msg":"trace[1766492057] transaction","detail":"{read_only:false; response_revision:26564; number_of_response:1; }","duration":"427.733862ms","start":"2026-10-09T06:16:33.849128Z","end":"2026-10-09T06:16:34.276862Z","steps":["trace[1766492057] 'process raft request' (duration: 427.528628ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:34.277022Z","caller":"traceutil/trace.go:171","msg":"trace[705035103] linearizableReadLoop","detail":"{readStateIndex:27452; appliedIndex:27449; }","duration":"296.730518ms","start":"2026-10-09T06:16:33.980276Z","end":"2026-10-09T06:16:34.277006Z","steps":["trace[705035103] 'read index received' (duration: 295.436354ms)","trace[705035103] 'applied index is now lower than readState.Index' (duration: 1.292974ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:34.277029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:33.849098Z","time spent":"427.857635ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:34.27717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.249238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1491d83f-e76b-4f72-9e2d-22acc705c832\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-10-09T06:16:34.277207Z","caller":"traceutil/trace.go:171","msg":"trace[1040961553] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1491d83f-e76b-4f72-9e2d-22acc705c832; range_end:; response_count:1; response_revision:26565; }","duration":"819.31292ms","start":"2026-10-09T06:16:33.457883Z","end":"2026-10-09T06:16:34.277196Z","steps":["trace[1040961553] 'agreement among raft nodes before linearized reading' (duration: 819.176627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:34.277233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:33.457871Z","time spent":"819.35598ms","remote":"127.0.0.1:35510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1347,"request content":"key:\"/registry/persistentvolumes/pvc-1491d83f-e76b-4f72-9e2d-22acc705c832\" "} {"level":"warn","ts":"2026-10-09T06:16:34.277446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.492559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:34.277486Z","caller":"traceutil/trace.go:171","msg":"trace[1202005851] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:26565; }","duration":"474.570161ms","start":"2026-10-09T06:16:33.802903Z","end":"2026-10-09T06:16:34.277473Z","steps":["trace[1202005851] 'agreement among raft nodes before linearized reading' (duration: 474.50014ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:34.277503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.857435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:16:34.277472Z","caller":"traceutil/trace.go:171","msg":"trace[2048364619] transaction","detail":"{read_only:false; response_revision:26565; number_of_response:1; }","duration":"408.265783ms","start":"2026-10-09T06:16:33.869172Z","end":"2026-10-09T06:16:34.277438Z","steps":["trace[2048364619] 'process raft request' (duration: 407.643321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:34.277514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:33.802885Z","time spent":"474.621512ms","remote":"127.0.0.1:54196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"info","ts":"2026-10-09T06:16:34.277533Z","caller":"traceutil/trace.go:171","msg":"trace[1431000420] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:26565; }","duration":"630.888636ms","start":"2026-10-09T06:16:33.646635Z","end":"2026-10-09T06:16:34.277523Z","steps":["trace[1431000420] 'agreement among raft nodes before linearized reading' (duration: 630.824115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:34.277559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:33.646577Z","time spent":"630.974129ms","remote":"127.0.0.1:35694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":22,"response size":32,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-10-09T06:16:34.277632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:33.869151Z","time spent":"408.396206ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:38.39337Z","caller":"traceutil/trace.go:171","msg":"trace[1707453293] transaction","detail":"{read_only:false; response_revision:26647; number_of_response:1; }","duration":"105.022882ms","start":"2026-10-09T06:16:38.288326Z","end":"2026-10-09T06:16:38.393349Z","steps":["trace[1707453293] 'process raft request' (duration: 104.88275ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:38.405095Z","caller":"traceutil/trace.go:171","msg":"trace[1685208175] transaction","detail":"{read_only:false; response_revision:26648; number_of_response:1; }","duration":"113.010394ms","start":"2026-10-09T06:16:38.29206Z","end":"2026-10-09T06:16:38.40507Z","steps":["trace[1685208175] 'process raft request' (duration: 112.83108ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:38.405368Z","caller":"traceutil/trace.go:171","msg":"trace[851498857] transaction","detail":"{read_only:false; response_revision:26649; number_of_response:1; }","duration":"112.892401ms","start":"2026-10-09T06:16:38.292462Z","end":"2026-10-09T06:16:38.405354Z","steps":["trace[851498857] 'process raft request' (duration: 112.560365ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:38.783617Z","caller":"traceutil/trace.go:171","msg":"trace[540943862] linearizableReadLoop","detail":"{readStateIndex:27538; appliedIndex:27537; }","duration":"287.37796ms","start":"2026-10-09T06:16:38.496211Z","end":"2026-10-09T06:16:38.783589Z","steps":["trace[540943862] 'read index received' (duration: 287.242649ms)","trace[540943862] 'applied index is now lower than readState.Index' (duration: 134.291µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:38.783669Z","caller":"traceutil/trace.go:171","msg":"trace[1420134821] transaction","detail":"{read_only:false; response_revision:26650; number_of_response:1; }","duration":"318.147104ms","start":"2026-10-09T06:16:38.465487Z","end":"2026-10-09T06:16:38.783634Z","steps":["trace[1420134821] 'process raft request' (duration: 317.924851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:38.783778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.539174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:16:38.783849Z","caller":"traceutil/trace.go:171","msg":"trace[482368883] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:26650; }","duration":"287.702377ms","start":"2026-10-09T06:16:38.496132Z","end":"2026-10-09T06:16:38.783834Z","steps":["trace[482368883] 'agreement among raft nodes before linearized reading' (duration: 287.548134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:38.783847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:38.465466Z","time spent":"318.270466ms","remote":"127.0.0.1:35550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3743,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:16:38.944042Z","caller":"traceutil/trace.go:171","msg":"trace[1683168501] transaction","detail":"{read_only:false; response_revision:26651; number_of_response:1; }","duration":"254.15629ms","start":"2026-10-09T06:16:38.689854Z","end":"2026-10-09T06:16:38.94401Z","steps":["trace[1683168501] 'process raft request' (duration: 253.931896ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:38.944353Z","caller":"traceutil/trace.go:171","msg":"trace[548606035] transaction","detail":"{read_only:false; response_revision:26652; number_of_response:1; }","duration":"245.071177ms","start":"2026-10-09T06:16:38.699249Z","end":"2026-10-09T06:16:38.94432Z","steps":["trace[548606035] 'process raft request' (duration: 244.69289ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:40.819288Z","caller":"traceutil/trace.go:171","msg":"trace[1926661893] transaction","detail":"{read_only:false; response_revision:26668; number_of_response:1; }","duration":"139.321083ms","start":"2026-10-09T06:16:40.679941Z","end":"2026-10-09T06:16:40.819263Z","steps":["trace[1926661893] 'process raft request' (duration: 139.127499ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:45.852974Z","caller":"traceutil/trace.go:171","msg":"trace[340663860] linearizableReadLoop","detail":"{readStateIndex:27616; appliedIndex:27614; }","duration":"345.91491ms","start":"2026-10-09T06:16:45.507032Z","end":"2026-10-09T06:16:45.852946Z","steps":["trace[340663860] 'read index received' (duration: 19.958419ms)","trace[340663860] 'applied index is now lower than readState.Index' (duration: 325.955451ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:45.853302Z","caller":"traceutil/trace.go:171","msg":"trace[1228054370] transaction","detail":"{read_only:false; response_revision:26727; number_of_response:1; }","duration":"364.553905ms","start":"2026-10-09T06:16:45.488721Z","end":"2026-10-09T06:16:45.853275Z","steps":["trace[1228054370] 'process raft request' (duration: 306.316541ms)","trace[1228054370] 'compare' (duration: 57.54005ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:16:45.853424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:45.488693Z","time spent":"364.653066ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:16:45.853413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.369389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T06:16:45.853491Z","caller":"traceutil/trace.go:171","msg":"trace[1262579923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26727; }","duration":"346.499782ms","start":"2026-10-09T06:16:45.506973Z","end":"2026-10-09T06:16:45.853473Z","steps":["trace[1262579923] 'agreement among raft nodes before linearized reading' (duration: 346.295108ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:45.853527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:45.506952Z","time spent":"346.567643ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T06:16:45.978067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.622784ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-09T06:16:45.978573Z","caller":"traceutil/trace.go:171","msg":"trace[1733695913] transaction","detail":"{read_only:false; response_revision:26728; number_of_response:1; }","duration":"154.174394ms","start":"2026-10-09T06:16:45.824368Z","end":"2026-10-09T06:16:45.978542Z","steps":["trace[1733695913] 'process raft request' (duration: 153.771607ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:45.980431Z","caller":"traceutil/trace.go:171","msg":"trace[1036133530] linearizableReadLoop","detail":"{readStateIndex:27618; appliedIndex:27616; }","duration":"127.365887ms","start":"2026-10-09T06:16:45.853049Z","end":"2026-10-09T06:16:45.980415Z","steps":["trace[1036133530] 'read index received' (duration: 10.431079ms)","trace[1036133530] 'applied index is now lower than readState.Index' (duration: 116.933898ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:16:45.980568Z","caller":"traceutil/trace.go:171","msg":"trace[821393501] transaction","detail":"{read_only:false; response_revision:26729; number_of_response:1; }","duration":"121.763921ms","start":"2026-10-09T06:16:45.858785Z","end":"2026-10-09T06:16:45.980549Z","steps":["trace[821393501] 'process raft request' (duration: 119.738091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:45.980557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.133587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:16:45.980592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.683715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-09T06:16:45.980584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.022835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:16:45.98063Z","caller":"traceutil/trace.go:171","msg":"trace[891107633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26729; }","duration":"472.772857ms","start":"2026-10-09T06:16:45.507845Z","end":"2026-10-09T06:16:45.980618Z","steps":["trace[891107633] 'agreement among raft nodes before linearized reading' (duration: 472.646054ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:16:45.980641Z","caller":"traceutil/trace.go:171","msg":"trace[676748393] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:26729; }","duration":"471.118566ms","start":"2026-10-09T06:16:45.509507Z","end":"2026-10-09T06:16:45.980626Z","steps":["trace[676748393] 'agreement among raft nodes before linearized reading' (duration: 470.986644ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:45.980658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:45.507821Z","time spent":"472.829729ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T06:16:45.980661Z","caller":"traceutil/trace.go:171","msg":"trace[31744542] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:26729; }","duration":"277.228879ms","start":"2026-10-09T06:16:45.703388Z","end":"2026-10-09T06:16:45.980617Z","steps":["trace[31744542] 'agreement among raft nodes before linearized reading' (duration: 277.086026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:16:45.980684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:16:45.5095Z","time spent":"471.173337ms","remote":"127.0.0.1:47822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":32,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-10-09T06:16:51.48318Z","caller":"traceutil/trace.go:171","msg":"trace[1132886445] transaction","detail":"{read_only:false; response_revision:26775; number_of_response:1; }","duration":"111.26224ms","start":"2026-10-09T06:16:51.371885Z","end":"2026-10-09T06:16:51.483147Z","steps":["trace[1132886445] 'process raft request' (duration: 111.016725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:09.814226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.314174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:17:09.814298Z","caller":"traceutil/trace.go:171","msg":"trace[1319943106] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:26922; }","duration":"128.464596ms","start":"2026-10-09T06:17:09.685816Z","end":"2026-10-09T06:17:09.81428Z","steps":["trace[1319943106] 'count revisions from in-memory index tree' (duration: 128.17326ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:10.044093Z","caller":"traceutil/trace.go:171","msg":"trace[245364885] linearizableReadLoop","detail":"{readStateIndex:27817; appliedIndex:27816; }","duration":"150.79547ms","start":"2026-10-09T06:17:09.893272Z","end":"2026-10-09T06:17:10.044067Z","steps":["trace[245364885] 'read index received' (duration: 150.600796ms)","trace[245364885] 'applied index is now lower than readState.Index' (duration: 193.374µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:10.044198Z","caller":"traceutil/trace.go:171","msg":"trace[2129777447] transaction","detail":"{read_only:false; response_revision:26923; number_of_response:1; }","duration":"225.536928ms","start":"2026-10-09T06:17:09.818615Z","end":"2026-10-09T06:17:10.044152Z","steps":["trace[2129777447] 'process raft request' (duration: 225.307653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:10.044248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.955723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:10.044287Z","caller":"traceutil/trace.go:171","msg":"trace[630856151] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:26923; }","duration":"151.066905ms","start":"2026-10-09T06:17:09.893206Z","end":"2026-10-09T06:17:10.044273Z","steps":["trace[630856151] 'agreement among raft nodes before linearized reading' (duration: 150.977423ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:10.196604Z","caller":"traceutil/trace.go:171","msg":"trace[1010698218] transaction","detail":"{read_only:false; response_revision:26924; number_of_response:1; }","duration":"178.12061ms","start":"2026-10-09T06:17:10.018463Z","end":"2026-10-09T06:17:10.196584Z","steps":["trace[1010698218] 'process raft request' (duration: 97.51981ms)","trace[1010698218] 'compare' (duration: 80.433686ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:10.196581Z","caller":"traceutil/trace.go:171","msg":"trace[719303767] linearizableReadLoop","detail":"{readStateIndex:27818; appliedIndex:27817; }","duration":"152.389321ms","start":"2026-10-09T06:17:10.04416Z","end":"2026-10-09T06:17:10.19655Z","steps":["trace[719303767] 'read index received' (duration: 71.749931ms)","trace[719303767] 'applied index is now lower than readState.Index' (duration: 80.63821ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:17:10.196707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.617842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:17:10.19677Z","caller":"traceutil/trace.go:171","msg":"trace[1116977503] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:26924; }","duration":"281.725085ms","start":"2026-10-09T06:17:09.915015Z","end":"2026-10-09T06:17:10.19674Z","steps":["trace[1116977503] 'agreement among raft nodes before linearized reading' (duration: 281.615832ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:10.47242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.520587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:17:10.472895Z","caller":"traceutil/trace.go:171","msg":"trace[220563739] transaction","detail":"{read_only:false; response_revision:26928; number_of_response:1; }","duration":"269.862299ms","start":"2026-10-09T06:17:10.202985Z","end":"2026-10-09T06:17:10.472848Z","steps":["trace[220563739] 'process raft request' (duration: 269.614704ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:10.472948Z","caller":"traceutil/trace.go:171","msg":"trace[1206802968] transaction","detail":"{read_only:false; response_revision:26926; number_of_response:1; }","duration":"271.897057ms","start":"2026-10-09T06:17:10.200986Z","end":"2026-10-09T06:17:10.472883Z","steps":["trace[1206802968] 'process raft request' (duration: 271.53186ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:10.47303Z","caller":"traceutil/trace.go:171","msg":"trace[679874484] transaction","detail":"{read_only:false; response_revision:26925; number_of_response:1; }","duration":"272.781904ms","start":"2026-10-09T06:17:10.200214Z","end":"2026-10-09T06:17:10.472996Z","steps":["trace[679874484] 'process raft request' (duration: 123.602934ms)","trace[679874484] 'compare' (duration: 148.377024ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:10.473116Z","caller":"traceutil/trace.go:171","msg":"trace[1151586659] transaction","detail":"{read_only:false; response_revision:26927; number_of_response:1; }","duration":"271.760235ms","start":"2026-10-09T06:17:10.201339Z","end":"2026-10-09T06:17:10.473099Z","steps":["trace[1151586659] 'process raft request' (duration: 271.226055ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:10.76506Z","caller":"traceutil/trace.go:171","msg":"trace[1892761005] transaction","detail":"{read_only:false; response_revision:26929; number_of_response:1; }","duration":"232.948578ms","start":"2026-10-09T06:17:10.532086Z","end":"2026-10-09T06:17:10.765034Z","steps":["trace[1892761005] 'process raft request' (duration: 232.695083ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:10.765276Z","caller":"traceutil/trace.go:171","msg":"trace[2029039279] transaction","detail":"{read_only:false; response_revision:26930; number_of_response:1; }","duration":"230.528612ms","start":"2026-10-09T06:17:10.53472Z","end":"2026-10-09T06:17:10.765249Z","steps":["trace[2029039279] 'process raft request' (duration: 230.269417ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:10.817412Z","caller":"traceutil/trace.go:171","msg":"trace[2064744027] linearizableReadLoop","detail":"{readStateIndex:27827; appliedIndex:27823; }","duration":"277.777159ms","start":"2026-10-09T06:17:10.539612Z","end":"2026-10-09T06:17:10.817389Z","steps":["trace[2064744027] 'read index received' (duration: 225.203132ms)","trace[2064744027] 'applied index is now lower than readState.Index' (duration: 52.572867ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:10.817505Z","caller":"traceutil/trace.go:171","msg":"trace[992225174] transaction","detail":"{read_only:false; response_revision:26932; number_of_response:1; }","duration":"278.030174ms","start":"2026-10-09T06:17:10.539454Z","end":"2026-10-09T06:17:10.817484Z","steps":["trace[992225174] 'process raft request' (duration: 277.880431ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:10.817576Z","caller":"traceutil/trace.go:171","msg":"trace[2103202728] transaction","detail":"{read_only:false; response_revision:26931; number_of_response:1; }","duration":"279.018161ms","start":"2026-10-09T06:17:10.53854Z","end":"2026-10-09T06:17:10.817558Z","steps":["trace[2103202728] 'process raft request' (duration: 278.673724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:10.817615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.966492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:10.817691Z","caller":"traceutil/trace.go:171","msg":"trace[648375155] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:26932; }","duration":"278.089744ms","start":"2026-10-09T06:17:10.53958Z","end":"2026-10-09T06:17:10.81767Z","steps":["trace[648375155] 'agreement among raft nodes before linearized reading' (duration: 277.939341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:10.817583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.240747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:10.817844Z","caller":"traceutil/trace.go:171","msg":"trace[726838744] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:26932; }","duration":"220.572123ms","start":"2026-10-09T06:17:10.597255Z","end":"2026-10-09T06:17:10.817827Z","steps":["trace[726838744] 'agreement among raft nodes before linearized reading' (duration: 220.256257ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:11.310431Z","caller":"traceutil/trace.go:171","msg":"trace[951787994] transaction","detail":"{read_only:false; response_revision:26934; number_of_response:1; }","duration":"219.206538ms","start":"2026-10-09T06:17:11.091195Z","end":"2026-10-09T06:17:11.310402Z","steps":["trace[951787994] 'process raft request' (duration: 149.756701ms)","trace[951787994] 'compare' (duration: 69.323125ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:11.311814Z","caller":"traceutil/trace.go:171","msg":"trace[1300600238] transaction","detail":"{read_only:false; response_revision:26935; number_of_response:1; }","duration":"131.178068ms","start":"2026-10-09T06:17:11.180601Z","end":"2026-10-09T06:17:11.311779Z","steps":["trace[1300600238] 'process raft request' (duration: 130.348773ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:11.311827Z","caller":"traceutil/trace.go:171","msg":"trace[1014879392] transaction","detail":"{read_only:false; response_revision:26936; number_of_response:1; }","duration":"130.094167ms","start":"2026-10-09T06:17:11.181695Z","end":"2026-10-09T06:17:11.311789Z","steps":["trace[1014879392] 'process raft request' (duration: 129.803272ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:11.31183Z","caller":"traceutil/trace.go:171","msg":"trace[941130348] transaction","detail":"{read_only:false; response_revision:26937; number_of_response:1; }","duration":"126.347307ms","start":"2026-10-09T06:17:11.185448Z","end":"2026-10-09T06:17:11.311796Z","steps":["trace[941130348] 'process raft request' (duration: 126.112863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:11.559133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.288456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:17:11.55926Z","caller":"traceutil/trace.go:171","msg":"trace[1336183717] linearizableReadLoop","detail":"{readStateIndex:27833; appliedIndex:27832; }","duration":"189.078827ms","start":"2026-10-09T06:17:11.370158Z","end":"2026-10-09T06:17:11.559237Z","steps":["trace[1336183717] 'read index received' (duration: 82.538446ms)","trace[1336183717] 'applied index is now lower than readState.Index' (duration: 106.538591ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:11.5593Z","caller":"traceutil/trace.go:171","msg":"trace[754030659] transaction","detail":"{read_only:false; response_revision:26938; number_of_response:1; }","duration":"241.644093ms","start":"2026-10-09T06:17:11.317626Z","end":"2026-10-09T06:17:11.55927Z","steps":["trace[754030659] 'process raft request' (duration: 135.072902ms)","trace[754030659] 'compare' (duration: 105.956299ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:17:11.559416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.263571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:11.55947Z","caller":"traceutil/trace.go:171","msg":"trace[1977915285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26938; }","duration":"189.363731ms","start":"2026-10-09T06:17:11.370092Z","end":"2026-10-09T06:17:11.559456Z","steps":["trace[1977915285] 'agreement among raft nodes before linearized reading' (duration: 189.270969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:11.559625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.412381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:11.559713Z","caller":"traceutil/trace.go:171","msg":"trace[1424193561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26938; }","duration":"162.523312ms","start":"2026-10-09T06:17:11.397152Z","end":"2026-10-09T06:17:11.559675Z","steps":["trace[1424193561] 'agreement among raft nodes before linearized reading' (duration: 162.42106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:12.295745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.21254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:12.295877Z","caller":"traceutil/trace.go:171","msg":"trace[1627821262] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26939; }","duration":"129.405954ms","start":"2026-10-09T06:17:12.166448Z","end":"2026-10-09T06:17:12.295854Z","steps":["trace[1627821262] 'range keys from in-memory index tree' (duration: 129.058818ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:12.561809Z","caller":"traceutil/trace.go:171","msg":"trace[328740020] linearizableReadLoop","detail":"{readStateIndex:27836; appliedIndex:27835; }","duration":"164.301547ms","start":"2026-10-09T06:17:12.397481Z","end":"2026-10-09T06:17:12.561783Z","steps":["trace[328740020] 'read index received' (duration: 163.97157ms)","trace[328740020] 'applied index is now lower than readState.Index' (duration: 328.717µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:12.561865Z","caller":"traceutil/trace.go:171","msg":"trace[753840175] transaction","detail":"{read_only:false; response_revision:26941; number_of_response:1; }","duration":"203.42851ms","start":"2026-10-09T06:17:12.358406Z","end":"2026-10-09T06:17:12.561834Z","steps":["trace[753840175] 'process raft request' (duration: 203.147554ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:12.562058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.528279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:17:12.562047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.550131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:12.562115Z","caller":"traceutil/trace.go:171","msg":"trace[1856453585] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:26941; }","duration":"136.607631ms","start":"2026-10-09T06:17:12.425487Z","end":"2026-10-09T06:17:12.562094Z","steps":["trace[1856453585] 'agreement among raft nodes before linearized reading' (duration: 136.456568ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:12.562149Z","caller":"traceutil/trace.go:171","msg":"trace[509977532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26941; }","duration":"164.725274ms","start":"2026-10-09T06:17:12.3974Z","end":"2026-10-09T06:17:12.562125Z","steps":["trace[509977532] 'agreement among raft nodes before linearized reading' (duration: 164.50074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:12.562571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.838343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-10-09T06:17:12.562609Z","caller":"traceutil/trace.go:171","msg":"trace[1396875939] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:26941; }","duration":"110.905274ms","start":"2026-10-09T06:17:12.451687Z","end":"2026-10-09T06:17:12.562592Z","steps":["trace[1396875939] 'agreement among raft nodes before linearized reading' (duration: 110.811862ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:18.960912Z","caller":"traceutil/trace.go:171","msg":"trace[1323054859] transaction","detail":"{read_only:false; response_revision:26993; number_of_response:1; }","duration":"119.013237ms","start":"2026-10-09T06:17:18.841877Z","end":"2026-10-09T06:17:18.96089Z","steps":["trace[1323054859] 'process raft request' (duration: 118.931185ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:18.9611Z","caller":"traceutil/trace.go:171","msg":"trace[1717870887] transaction","detail":"{read_only:false; response_revision:26992; number_of_response:1; }","duration":"169.323753ms","start":"2026-10-09T06:17:18.791762Z","end":"2026-10-09T06:17:18.961085Z","steps":["trace[1717870887] 'process raft request' (duration: 164.474781ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:24.459237Z","caller":"traceutil/trace.go:171","msg":"trace[1554074327] transaction","detail":"{read_only:false; response_revision:27028; number_of_response:1; }","duration":"173.897439ms","start":"2026-10-09T06:17:24.28528Z","end":"2026-10-09T06:17:24.459177Z","steps":["trace[1554074327] 'process raft request' (duration: 141.906031ms)","trace[1554074327] 'compare' (duration: 31.631391ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:24.459373Z","caller":"traceutil/trace.go:171","msg":"trace[1284683721] transaction","detail":"{read_only:false; response_revision:27029; number_of_response:1; }","duration":"120.306952ms","start":"2026-10-09T06:17:24.339039Z","end":"2026-10-09T06:17:24.459346Z","steps":["trace[1284683721] 'process raft request' (duration: 119.929285ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:24.876164Z","caller":"traceutil/trace.go:171","msg":"trace[1426636911] transaction","detail":"{read_only:false; response_revision:27031; number_of_response:1; }","duration":"189.856231ms","start":"2026-10-09T06:17:24.686281Z","end":"2026-10-09T06:17:24.876137Z","steps":["trace[1426636911] 'process raft request' (duration: 189.698718ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:25.002536Z","caller":"traceutil/trace.go:171","msg":"trace[1541778377] linearizableReadLoop","detail":"{readStateIndex:27931; appliedIndex:27928; }","duration":"312.816793ms","start":"2026-10-09T06:17:24.689694Z","end":"2026-10-09T06:17:25.002511Z","steps":["trace[1541778377] 'read index received' (duration: 186.290653ms)","trace[1541778377] 'applied index is now lower than readState.Index' (duration: 126.52502ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:17:25.002706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.986627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:25.00277Z","caller":"traceutil/trace.go:171","msg":"trace[885625707] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:27034; }","duration":"313.071318ms","start":"2026-10-09T06:17:24.689662Z","end":"2026-10-09T06:17:25.002733Z","steps":["trace[885625707] 'agreement among raft nodes before linearized reading' (duration: 312.965986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:25.002811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:17:24.68965Z","time spent":"313.149679ms","remote":"127.0.0.1:40480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-10-09T06:17:25.002967Z","caller":"traceutil/trace.go:171","msg":"trace[425154366] transaction","detail":"{read_only:false; response_revision:27032; number_of_response:1; }","duration":"316.249179ms","start":"2026-10-09T06:17:24.6867Z","end":"2026-10-09T06:17:25.00295Z","steps":["trace[425154366] 'process raft request' (duration: 301.823195ms)","trace[425154366] 'compare' (duration: 13.606708ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:25.002984Z","caller":"traceutil/trace.go:171","msg":"trace[296116679] transaction","detail":"{read_only:false; response_revision:27034; number_of_response:1; }","duration":"139.121338ms","start":"2026-10-09T06:17:24.863837Z","end":"2026-10-09T06:17:25.002958Z","steps":["trace[296116679] 'process raft request' (duration: 138.625158ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:25.00297Z","caller":"traceutil/trace.go:171","msg":"trace[1705261500] transaction","detail":"{read_only:false; response_revision:27033; number_of_response:1; }","duration":"314.496556ms","start":"2026-10-09T06:17:24.68844Z","end":"2026-10-09T06:17:25.002936Z","steps":["trace[1705261500] 'process raft request' (duration: 313.959045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:25.003244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:17:24.688425Z","time spent":"314.73468ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:17:25.003071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:17:24.686676Z","time spent":"316.33087ms","remote":"127.0.0.1:35634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:17:25.205501Z","caller":"traceutil/trace.go:171","msg":"trace[555983693] transaction","detail":"{read_only:false; response_revision:27035; number_of_response:1; }","duration":"223.086331ms","start":"2026-10-09T06:17:24.982387Z","end":"2026-10-09T06:17:25.205474Z","steps":["trace[555983693] 'process raft request' (duration: 206.008589ms)","trace[555983693] 'compare' (duration: 16.40368ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:25.205684Z","caller":"traceutil/trace.go:171","msg":"trace[1940481642] linearizableReadLoop","detail":"{readStateIndex:27934; appliedIndex:27932; }","duration":"142.37088ms","start":"2026-10-09T06:17:25.063292Z","end":"2026-10-09T06:17:25.205663Z","steps":["trace[1940481642] 'read index received' (duration: 125.012822ms)","trace[1940481642] 'applied index is now lower than readState.Index' (duration: 17.357178ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:25.20587Z","caller":"traceutil/trace.go:171","msg":"trace[1126279638] transaction","detail":"{read_only:false; response_revision:27036; number_of_response:1; }","duration":"222.331897ms","start":"2026-10-09T06:17:24.983526Z","end":"2026-10-09T06:17:25.205858Z","steps":["trace[1126279638] 'process raft request' (duration: 221.855049ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:25.206165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.860919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-09T06:17:25.206201Z","caller":"traceutil/trace.go:171","msg":"trace[906160956] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:27036; }","duration":"142.951051ms","start":"2026-10-09T06:17:25.063238Z","end":"2026-10-09T06:17:25.206189Z","steps":["trace[906160956] 'agreement among raft nodes before linearized reading' (duration: 142.807258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:25.206203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.230267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:25.206251Z","caller":"traceutil/trace.go:171","msg":"trace[1609186270] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:27036; }","duration":"104.322119ms","start":"2026-10-09T06:17:25.101914Z","end":"2026-10-09T06:17:25.206236Z","steps":["trace[1609186270] 'agreement among raft nodes before linearized reading' (duration: 104.223757ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:31.60655Z","caller":"traceutil/trace.go:171","msg":"trace[861047409] linearizableReadLoop","detail":"{readStateIndex:27983; appliedIndex:27982; }","duration":"209.462734ms","start":"2026-10-09T06:17:31.397059Z","end":"2026-10-09T06:17:31.606521Z","steps":["trace[861047409] 'read index received' (duration: 131.789881ms)","trace[861047409] 'applied index is now lower than readState.Index' (duration: 77.671773ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:31.606682Z","caller":"traceutil/trace.go:171","msg":"trace[604457043] transaction","detail":"{read_only:false; response_revision:27083; number_of_response:1; }","duration":"223.481021ms","start":"2026-10-09T06:17:31.38317Z","end":"2026-10-09T06:17:31.606651Z","steps":["trace[604457043] 'process raft request' (duration: 145.341138ms)","trace[604457043] 'compare' (duration: 77.882398ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:17:31.606778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.687168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:31.607325Z","caller":"traceutil/trace.go:171","msg":"trace[238939807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27083; }","duration":"210.30492ms","start":"2026-10-09T06:17:31.397002Z","end":"2026-10-09T06:17:31.607307Z","steps":["trace[238939807] 'agreement among raft nodes before linearized reading' (duration: 209.690098ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:31.671453Z","caller":"traceutil/trace.go:171","msg":"trace[2115269968] transaction","detail":"{read_only:false; response_revision:27085; number_of_response:1; }","duration":"261.956792ms","start":"2026-10-09T06:17:31.409456Z","end":"2026-10-09T06:17:31.671413Z","steps":["trace[2115269968] 'process raft request' (duration: 261.837729ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:31.6715Z","caller":"traceutil/trace.go:171","msg":"trace[1981383913] transaction","detail":"{read_only:false; response_revision:27084; number_of_response:1; }","duration":"262.553142ms","start":"2026-10-09T06:17:31.408895Z","end":"2026-10-09T06:17:31.671448Z","steps":["trace[1981383913] 'process raft request' (duration: 262.288477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:31.671522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.302226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:31.671601Z","caller":"traceutil/trace.go:171","msg":"trace[57175973] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:27086; }","duration":"146.397268ms","start":"2026-10-09T06:17:31.525181Z","end":"2026-10-09T06:17:31.671579Z","steps":["trace[57175973] 'agreement among raft nodes before linearized reading' (duration: 146.199844ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:31.671711Z","caller":"traceutil/trace.go:171","msg":"trace[804381977] transaction","detail":"{read_only:false; response_revision:27086; number_of_response:1; }","duration":"260.886471ms","start":"2026-10-09T06:17:31.410815Z","end":"2026-10-09T06:17:31.671701Z","steps":["trace[804381977] 'process raft request' (duration: 260.517834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:32.271792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.589566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:32.27188Z","caller":"traceutil/trace.go:171","msg":"trace[726380415] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:27087; }","duration":"181.755969ms","start":"2026-10-09T06:17:32.0901Z","end":"2026-10-09T06:17:32.271856Z","steps":["trace[726380415] 'range keys from in-memory index tree' (duration: 181.478833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:32.272139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.781245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:32.272234Z","caller":"traceutil/trace.go:171","msg":"trace[1678188274] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:27087; }","duration":"137.928498ms","start":"2026-10-09T06:17:32.134281Z","end":"2026-10-09T06:17:32.272209Z","steps":["trace[1678188274] 'count revisions from in-memory index tree' (duration: 137.669233ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:32.423976Z","caller":"traceutil/trace.go:171","msg":"trace[1915137491] transaction","detail":"{read_only:false; response_revision:27088; number_of_response:1; }","duration":"129.864814ms","start":"2026-10-09T06:17:32.294079Z","end":"2026-10-09T06:17:32.423944Z","steps":["trace[1915137491] 'process raft request' (duration: 129.404316ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:32.649572Z","caller":"traceutil/trace.go:171","msg":"trace[1500259799] transaction","detail":"{read_only:false; response_revision:27089; number_of_response:1; }","duration":"159.316623ms","start":"2026-10-09T06:17:32.490215Z","end":"2026-10-09T06:17:32.649531Z","steps":["trace[1500259799] 'process raft request' (duration: 94.465483ms)","trace[1500259799] 'compare' (duration: 64.641917ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:32.984783Z","caller":"traceutil/trace.go:171","msg":"trace[2033848798] linearizableReadLoop","detail":"{readStateIndex:27990; appliedIndex:27989; }","duration":"137.882256ms","start":"2026-10-09T06:17:32.846861Z","end":"2026-10-09T06:17:32.984743Z","steps":["trace[2033848798] 'read index received' (duration: 137.839415ms)","trace[2033848798] 'applied index is now lower than readState.Index' (duration: 41.921µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:32.984918Z","caller":"traceutil/trace.go:171","msg":"trace[266603739] transaction","detail":"{read_only:false; response_revision:27090; number_of_response:1; }","duration":"261.660415ms","start":"2026-10-09T06:17:32.723244Z","end":"2026-10-09T06:17:32.984904Z","steps":["trace[266603739] 'process raft request' (duration: 261.323899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:32.985294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.424727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:32.985376Z","caller":"traceutil/trace.go:171","msg":"trace[1746086672] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27090; }","duration":"138.512068ms","start":"2026-10-09T06:17:32.846844Z","end":"2026-10-09T06:17:32.985356Z","steps":["trace[1746086672] 'agreement among raft nodes before linearized reading' (duration: 138.399907ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:33.242558Z","caller":"traceutil/trace.go:171","msg":"trace[668362878] linearizableReadLoop","detail":"{readStateIndex:27992; appliedIndex:27991; }","duration":"100.331785ms","start":"2026-10-09T06:17:33.142154Z","end":"2026-10-09T06:17:33.242486Z","steps":["trace[668362878] 'read index received' (duration: 61.891485ms)","trace[668362878] 'applied index is now lower than readState.Index' (duration: 38.43847ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:33.242735Z","caller":"traceutil/trace.go:171","msg":"trace[402147706] transaction","detail":"{read_only:false; response_revision:27092; number_of_response:1; }","duration":"127.064871ms","start":"2026-10-09T06:17:33.115642Z","end":"2026-10-09T06:17:33.242707Z","steps":["trace[402147706] 'process raft request' (duration: 88.468238ms)","trace[402147706] 'compare' (duration: 38.109954ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:17:33.242855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.687912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T06:17:33.242898Z","caller":"traceutil/trace.go:171","msg":"trace[1517616101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27092; }","duration":"100.764183ms","start":"2026-10-09T06:17:33.142125Z","end":"2026-10-09T06:17:33.242889Z","steps":["trace[1517616101] 'agreement among raft nodes before linearized reading' (duration: 100.575289ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:33.541468Z","caller":"traceutil/trace.go:171","msg":"trace[2058773195] transaction","detail":"{read_only:false; response_revision:27095; number_of_response:1; }","duration":"165.818197ms","start":"2026-10-09T06:17:33.375622Z","end":"2026-10-09T06:17:33.54144Z","steps":["trace[2058773195] 'process raft request' (duration: 165.661024ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:33.579439Z","caller":"traceutil/trace.go:171","msg":"trace[2008382689] linearizableReadLoop","detail":"{readStateIndex:27996; appliedIndex:27994; }","duration":"179.871143ms","start":"2026-10-09T06:17:33.399545Z","end":"2026-10-09T06:17:33.579416Z","steps":["trace[2008382689] 'read index received' (duration: 141.851682ms)","trace[2008382689] 'applied index is now lower than readState.Index' (duration: 38.018321ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:17:33.579557Z","caller":"traceutil/trace.go:171","msg":"trace[179739985] transaction","detail":"{read_only:false; response_revision:27096; number_of_response:1; }","duration":"202.592634ms","start":"2026-10-09T06:17:33.376931Z","end":"2026-10-09T06:17:33.579524Z","steps":["trace[179739985] 'process raft request' (duration: 195.545841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:33.579606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.042456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:33.579639Z","caller":"traceutil/trace.go:171","msg":"trace[2065416464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27096; }","duration":"180.139668ms","start":"2026-10-09T06:17:33.399491Z","end":"2026-10-09T06:17:33.57963Z","steps":["trace[2065416464] 'agreement among raft nodes before linearized reading' (duration: 180.060716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:56.854347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.36842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:17:56.854449Z","caller":"traceutil/trace.go:171","msg":"trace[1215728961] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:27418; }","duration":"105.510242ms","start":"2026-10-09T06:17:56.748918Z","end":"2026-10-09T06:17:56.854429Z","steps":["trace[1215728961] 'count revisions from in-memory index tree' (duration: 105.290087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:56.854471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.357927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:17:56.854535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.09283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:56.854574Z","caller":"traceutil/trace.go:171","msg":"trace[1637872379] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27418; }","duration":"137.505069ms","start":"2026-10-09T06:17:56.717037Z","end":"2026-10-09T06:17:56.854542Z","steps":["trace[1637872379] 'range keys from in-memory index tree' (duration: 137.199204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:17:56.854579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.588112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:17:56.854536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.863405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:17:56.854682Z","caller":"traceutil/trace.go:171","msg":"trace[1647482551] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27418; }","duration":"135.733755ms","start":"2026-10-09T06:17:56.718921Z","end":"2026-10-09T06:17:56.854655Z","steps":["trace[1647482551] 'range keys from in-memory index tree' (duration: 135.515641ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:56.854716Z","caller":"traceutil/trace.go:171","msg":"trace[670906968] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:27418; }","duration":"138.064879ms","start":"2026-10-09T06:17:56.716642Z","end":"2026-10-09T06:17:56.854707Z","steps":["trace[670906968] 'range keys from in-memory index tree' (duration: 137.814584ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:56.854621Z","caller":"traceutil/trace.go:171","msg":"trace[1724374763] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27418; }","duration":"138.229592ms","start":"2026-10-09T06:17:56.716373Z","end":"2026-10-09T06:17:56.854602Z","steps":["trace[1724374763] 'range keys from in-memory index tree' (duration: 138.022099ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:56.905008Z","caller":"traceutil/trace.go:171","msg":"trace[104510718] transaction","detail":"{read_only:false; response_revision:27419; number_of_response:1; }","duration":"152.779739ms","start":"2026-10-09T06:17:56.752198Z","end":"2026-10-09T06:17:56.904978Z","steps":["trace[104510718] 'process raft request' (duration: 152.541774ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:57.285026Z","caller":"traceutil/trace.go:171","msg":"trace[479074676] transaction","detail":"{read_only:false; response_revision:27420; number_of_response:1; }","duration":"124.467292ms","start":"2026-10-09T06:17:57.15977Z","end":"2026-10-09T06:17:57.284237Z","steps":["trace[479074676] 'process raft request' (duration: 124.178607ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:18:07.169103Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24528} {"level":"info","ts":"2026-10-09T06:18:07.315248Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24528,"took":"141.542747ms","hash":2443366814,"current-db-size-bytes":58277888,"current-db-size":"58 MB","current-db-size-in-use-bytes":37572608,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-09T06:18:07.31534Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2443366814,"revision":24528,"compact-revision":21633} {"level":"info","ts":"2026-10-09T06:20:47.447383Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b9fef886b531fede","local-member-applied-index":30007,"local-member-snapshot-index":20006,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T06:20:47.45569Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30007} {"level":"info","ts":"2026-10-09T06:20:47.455829Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25007} {"level":"info","ts":"2026-10-09T06:23:07.177778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27522} {"level":"info","ts":"2026-10-09T06:23:07.32513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27522,"took":"143.078595ms","hash":113741998,"current-db-size-bytes":58277888,"current-db-size":"58 MB","current-db-size-in-use-bytes":35024896,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-09T06:23:07.325202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":113741998,"revision":27522,"compact-revision":24528} {"level":"info","ts":"2026-10-09T06:28:07.186159Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30240} {"level":"info","ts":"2026-10-09T06:28:07.335368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30240,"took":"144.943052ms","hash":3609559436,"current-db-size-bytes":58277888,"current-db-size":"58 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T06:28:07.335445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3609559436,"revision":30240,"compact-revision":27522} {"level":"info","ts":"2026-10-09T06:33:07.193703Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32491} {"level":"info","ts":"2026-10-09T06:33:07.372787Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32491,"took":"174.480234ms","hash":3273609833,"current-db-size-bytes":59318272,"current-db-size":"59 MB","current-db-size-in-use-bytes":30359552,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T06:33:07.372873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3273609833,"revision":32491,"compact-revision":30240} {"level":"info","ts":"2026-10-09T06:38:07.204311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34722} {"level":"info","ts":"2026-10-09T06:38:07.339148Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34722,"took":"131.604808ms","hash":3557012759,"current-db-size-bytes":59318272,"current-db-size":"59 MB","current-db-size-in-use-bytes":31268864,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T06:38:07.339273Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3557012759,"revision":34722,"compact-revision":32491} {"level":"info","ts":"2026-10-09T06:41:57.478476Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b9fef886b531fede","local-member-applied-index":40008,"local-member-snapshot-index":30007,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T06:41:57.485612Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40008} {"level":"info","ts":"2026-10-09T06:41:57.485729Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35008} {"level":"info","ts":"2026-10-09T06:43:07.212625Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37017} {"level":"info","ts":"2026-10-09T06:43:07.351775Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37017,"took":"134.953332ms","hash":3549255437,"current-db-size-bytes":59744256,"current-db-size":"60 MB","current-db-size-in-use-bytes":32653312,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T06:43:07.351842Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3549255437,"revision":37017,"compact-revision":34722} {"level":"info","ts":"2026-10-09T06:48:07.220587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39289} {"level":"info","ts":"2026-10-09T06:48:07.353518Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39289,"took":"129.39739ms","hash":3905709838,"current-db-size-bytes":61870080,"current-db-size":"62 MB","current-db-size-in-use-bytes":32055296,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T06:48:07.35359Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3905709838,"revision":39289,"compact-revision":37017}