{"level":"warn","ts":"2026-09-09T06:19:14.838174Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T06:19:14.838341Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-09T06:19:14.838454Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T06:19:14.838504Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-09-09T06:19:14.838558Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-09T06:19:14.839411Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-09-09T06:19:14.839584Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-09T06:19:14.844273Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.347716ms"} {"level":"info","ts":"2026-09-09T06:19:14.851887Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-09-09T06:19:14.851972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T06:19:14.852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-09-09T06:19:14.852012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T06:19:14.852026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-09-09T06:19:14.852067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-09-09T06:19:14.859033Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T06:19:14.862424Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T06:19:14.86459Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-09T06:19:14.867761Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T06:19:14.868058Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T06:19:14.868081Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-09T06:19:14.868303Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-09T06:19:14.868335Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-09T06:19:14.869432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-09-09T06:19:14.869612Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-09-09T06:19:14.871772Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-09T06:19:14.87196Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-09-09T06:19:14.872016Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-09-09T06:19:14.872117Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T06:19:14.872158Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T06:19:15.452478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T06:19:15.452563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T06:19:15.452598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-09-09T06:19:15.452618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-09-09T06:19:15.452631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-09-09T06:19:15.452649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-09-09T06:19:15.452662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-09-09T06:19:15.454419Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:19:15.455604Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:19:15.455609Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T06:19:15.455634Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:19:15.455938Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T06:19:15.455985Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T06:19:15.456335Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:19:15.45649Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:19:15.456537Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:19:15.458711Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-09-09T06:19:15.45881Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-09T06:19:35.341357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.260395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:19:35.341742Z","caller":"traceutil/trace.go:171","msg":"trace[446570000] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"204.05943ms","start":"2026-09-09T06:19:35.137621Z","end":"2026-09-09T06:19:35.34168Z","steps":["trace[446570000] 'process raft request' (duration: 49.898886ms)","trace[446570000] 'compare' (duration: 153.135812ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:19:57.532482Z","caller":"traceutil/trace.go:171","msg":"trace[2087818486] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"118.844359ms","start":"2026-09-09T06:19:57.413606Z","end":"2026-09-09T06:19:57.532451Z","steps":["trace[2087818486] 'process raft request' (duration: 92.179664ms)","trace[2087818486] 'compare' (duration: 26.47556ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:19:57.533499Z","caller":"traceutil/trace.go:171","msg":"trace[1284935259] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"110.111587ms","start":"2026-09-09T06:19:57.42336Z","end":"2026-09-09T06:19:57.533471Z","steps":["trace[1284935259] 'process raft request' (duration: 109.935113ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:19:57.533454Z","caller":"traceutil/trace.go:171","msg":"trace[452506123] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"115.400594ms","start":"2026-09-09T06:19:57.418Z","end":"2026-09-09T06:19:57.533401Z","steps":["trace[452506123] 'process raft request' (duration: 115.134518ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:02.693987Z","caller":"traceutil/trace.go:171","msg":"trace[223273691] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"136.318265ms","start":"2026-09-09T06:20:02.557611Z","end":"2026-09-09T06:20:02.693929Z","steps":["trace[223273691] 'process raft request' (duration: 131.99435ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:03.345262Z","caller":"traceutil/trace.go:171","msg":"trace[1076935315] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"240.463414ms","start":"2026-09-09T06:20:03.104767Z","end":"2026-09-09T06:20:03.34523Z","steps":["trace[1076935315] 'process raft request' (duration: 239.818661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:03.966991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.669485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:20:03.967368Z","caller":"traceutil/trace.go:171","msg":"trace[569048492] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"542.016882ms","start":"2026-09-09T06:20:03.425289Z","end":"2026-09-09T06:20:03.967306Z","steps":["trace[569048492] 'process raft request' (duration: 541.831767ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:03.967533Z","caller":"traceutil/trace.go:171","msg":"trace[926972532] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"616.496618ms","start":"2026-09-09T06:20:03.351Z","end":"2026-09-09T06:20:03.967496Z","steps":["trace[926972532] 'process raft request' (duration: 235.191208ms)","trace[926972532] 'compare' (duration: 380.497212ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:20:03.967574Z","caller":"traceutil/trace.go:171","msg":"trace[1087341293] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"399.317856ms","start":"2026-09-09T06:20:03.568197Z","end":"2026-09-09T06:20:03.967515Z","steps":["trace[1087341293] 'read index received' (duration: 18.094908ms)","trace[1087341293] 'applied index is now lower than readState.Index' (duration: 381.217577ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:20:03.967616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:03.425257Z","time spent":"542.271117ms","remote":"127.0.0.1:58152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T06:20:03.967735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:03.350958Z","time spent":"616.639461ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:20:03.972702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.209301ms","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-09-09T06:20:03.972772Z","caller":"traceutil/trace.go:171","msg":"trace[1977772689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"403.278322ms","start":"2026-09-09T06:20:03.569472Z","end":"2026-09-09T06:20:03.97275Z","steps":["trace[1977772689] 'agreement among raft nodes before linearized reading' (duration: 403.104438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:03.972815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:03.569396Z","time spent":"403.410785ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-09T06:20:03.972904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.959442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-09T06:20:03.972924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.688554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-09T06:20:03.97298Z","caller":"traceutil/trace.go:171","msg":"trace[1094268528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"361.096716ms","start":"2026-09-09T06:20:03.611864Z","end":"2026-09-09T06:20:03.972961Z","steps":["trace[1094268528] 'agreement among raft nodes before linearized reading' (duration: 360.87088ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:03.972988Z","caller":"traceutil/trace.go:171","msg":"trace[41259149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"404.840758ms","start":"2026-09-09T06:20:03.568129Z","end":"2026-09-09T06:20:03.97297Z","steps":["trace[41259149] 'agreement among raft nodes before linearized reading' (duration: 399.455569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:03.973019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:03.611841Z","time spent":"361.170247ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T06:20:03.973031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:03.568102Z","time spent":"404.918219ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-09T06:20:03.973123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.638839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8z8j2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-09T06:20:03.973227Z","caller":"traceutil/trace.go:171","msg":"trace[1271998779] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8z8j2; range_end:; response_count:1; response_revision:601; }","duration":"387.759271ms","start":"2026-09-09T06:20:03.585449Z","end":"2026-09-09T06:20:03.973208Z","steps":["trace[1271998779] 'agreement among raft nodes before linearized reading' (duration: 387.324641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:03.973261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:03.585437Z","time spent":"387.815773ms","remote":"127.0.0.1:58002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-8z8j2\" "} {"level":"warn","ts":"2026-09-09T06:20:20.50033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.334932ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:20:20.500454Z","caller":"traceutil/trace.go:171","msg":"trace[1120353106] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:772; }","duration":"372.478515ms","start":"2026-09-09T06:20:20.127955Z","end":"2026-09-09T06:20:20.500434Z","steps":["trace[1120353106] 'range keys from in-memory index tree' (duration: 372.314381ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:50.345375Z","caller":"traceutil/trace.go:171","msg":"trace[2011816553] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"115.975138ms","start":"2026-09-09T06:20:50.229376Z","end":"2026-09-09T06:20:50.345351Z","steps":["trace[2011816553] 'process raft request' (duration: 115.816095ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:50.357134Z","caller":"traceutil/trace.go:171","msg":"trace[983427374] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"126.451868ms","start":"2026-09-09T06:20:50.230666Z","end":"2026-09-09T06:20:50.357118Z","steps":["trace[983427374] 'process raft request' (duration: 126.346896ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:50.357319Z","caller":"traceutil/trace.go:171","msg":"trace[1135216302] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"126.081571ms","start":"2026-09-09T06:20:50.231214Z","end":"2026-09-09T06:20:50.357295Z","steps":["trace[1135216302] 'process raft request' (duration: 125.840266ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:50.357269Z","caller":"traceutil/trace.go:171","msg":"trace[1588656016] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"126.94023ms","start":"2026-09-09T06:20:50.230256Z","end":"2026-09-09T06:20:50.357196Z","steps":["trace[1588656016] 'process raft request' (duration: 126.659064ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:06.068491Z","caller":"traceutil/trace.go:171","msg":"trace[1894757754] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"135.745253ms","start":"2026-09-09T06:21:05.932714Z","end":"2026-09-09T06:21:06.06846Z","steps":["trace[1894757754] 'process raft request' (duration: 131.120072ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:40.459402Z","caller":"traceutil/trace.go:171","msg":"trace[1877126167] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"342.93037ms","start":"2026-09-09T06:21:40.11643Z","end":"2026-09-09T06:21:40.45936Z","steps":["trace[1877126167] 'process raft request' (duration: 342.683924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:40.459715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:21:40.116407Z","time spent":"343.164405ms","remote":"127.0.0.1:58002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:21:40.603307Z","caller":"traceutil/trace.go:171","msg":"trace[1552192002] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"114.549674ms","start":"2026-09-09T06:21:40.48869Z","end":"2026-09-09T06:21:40.603239Z","steps":["trace[1552192002] 'process raft request' (duration: 113.551763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:40.904747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.325109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:21:40.904909Z","caller":"traceutil/trace.go:171","msg":"trace[1617360595] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1928; }","duration":"294.002061ms","start":"2026-09-09T06:21:40.61088Z","end":"2026-09-09T06:21:40.904882Z","steps":["trace[1617360595] 'read index received' (duration: 141.348426ms)","trace[1617360595] 'applied index is now lower than readState.Index' (duration: 152.651835ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:21:40.904967Z","caller":"traceutil/trace.go:171","msg":"trace[2072190059] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"294.367069ms","start":"2026-09-09T06:21:40.61053Z","end":"2026-09-09T06:21:40.904898Z","steps":["trace[2072190059] 'process raft request' (duration: 141.720624ms)","trace[2072190059] 'compare' (duration: 152.136483ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:21:40.90518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.284287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-df6254b1-0837-4aec-9662-870f291372c6\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-09-09T06:21:40.90525Z","caller":"traceutil/trace.go:171","msg":"trace[1786196322] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-df6254b1-0837-4aec-9662-870f291372c6; range_end:; response_count:1; response_revision:1858; }","duration":"294.433571ms","start":"2026-09-09T06:21:40.610795Z","end":"2026-09-09T06:21:40.905228Z","steps":["trace[1786196322] 'agreement among raft nodes before linearized reading' (duration: 294.215466ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:40.920066Z","caller":"traceutil/trace.go:171","msg":"trace[358100066] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"308.670726ms","start":"2026-09-09T06:21:40.611361Z","end":"2026-09-09T06:21:40.920032Z","steps":["trace[358100066] 'process raft request' (duration: 308.43252ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:40.920371Z","caller":"traceutil/trace.go:171","msg":"trace[1977714777] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"308.710546ms","start":"2026-09-09T06:21:40.611625Z","end":"2026-09-09T06:21:40.920336Z","steps":["trace[1977714777] 'process raft request' (duration: 308.359909ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:40.920401Z","caller":"traceutil/trace.go:171","msg":"trace[511732063] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"308.207394ms","start":"2026-09-09T06:21:40.612175Z","end":"2026-09-09T06:21:40.920382Z","steps":["trace[511732063] 'process raft request' (duration: 308.02724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:40.920431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:21:40.611342Z","time spent":"308.829269ms","remote":"127.0.0.1:58068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:21:40.920508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:21:40.611613Z","time spent":"308.838279ms","remote":"127.0.0.1:58068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:21:40.920549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:21:40.612163Z","time spent":"308.293587ms","remote":"127.0.0.1:57990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:21:40.920579Z","caller":"traceutil/trace.go:171","msg":"trace[1480691030] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"306.14103ms","start":"2026-09-09T06:21:40.61441Z","end":"2026-09-09T06:21:40.920551Z","steps":["trace[1480691030] 'process raft request' (duration: 305.907295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:40.920701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:21:40.614396Z","time spent":"306.240522ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:21:40.920712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.717387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d392cfb36bfa90\" ","response":"range_response_count:1 size:982"} {"level":"warn","ts":"2026-09-09T06:21:40.920733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.838609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T06:21:40.920759Z","caller":"traceutil/trace.go:171","msg":"trace[668088076] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d392cfb36bfa90; range_end:; response_count:1; response_revision:1862; }","duration":"289.779178ms","start":"2026-09-09T06:21:40.630965Z","end":"2026-09-09T06:21:40.920745Z","steps":["trace[668088076] 'agreement among raft nodes before linearized reading' (duration: 289.656675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:40.920753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.689406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-09T06:21:40.920786Z","caller":"traceutil/trace.go:171","msg":"trace[1794051695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1862; }","duration":"289.937741ms","start":"2026-09-09T06:21:40.630832Z","end":"2026-09-09T06:21:40.92077Z","steps":["trace[1794051695] 'agreement among raft nodes before linearized reading' (duration: 289.783168ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:40.920818Z","caller":"traceutil/trace.go:171","msg":"trace[675285170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1862; }","duration":"284.801618ms","start":"2026-09-09T06:21:40.635998Z","end":"2026-09-09T06:21:40.9208Z","steps":["trace[675285170] 'agreement among raft nodes before linearized reading' (duration: 284.612874ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:40.920758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.935298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-09T06:21:40.920887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.035304ms","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-09-09T06:21:40.920912Z","caller":"traceutil/trace.go:171","msg":"trace[1527594533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1862; }","duration":"293.117421ms","start":"2026-09-09T06:21:40.627781Z","end":"2026-09-09T06:21:40.920899Z","steps":["trace[1527594533] 'agreement among raft nodes before linearized reading' (duration: 292.826105ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:40.920978Z","caller":"traceutil/trace.go:171","msg":"trace[516683463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1862; }","duration":"290.143767ms","start":"2026-09-09T06:21:40.630816Z","end":"2026-09-09T06:21:40.92096Z","steps":["trace[516683463] 'agreement among raft nodes before linearized reading' (duration: 289.983133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:53.295809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.479285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:21:53.296057Z","caller":"traceutil/trace.go:171","msg":"trace[2059697173] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"232.757681ms","start":"2026-09-09T06:21:53.063273Z","end":"2026-09-09T06:21:53.29603Z","steps":["trace[2059697173] 'process raft request' (duration: 97.56861ms)","trace[2059697173] 'compare' (duration: 134.343632ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:21:53.296188Z","caller":"traceutil/trace.go:171","msg":"trace[1406804909] linearizableReadLoop","detail":"{readStateIndex:2040; appliedIndex:2038; }","duration":"229.321216ms","start":"2026-09-09T06:21:53.066837Z","end":"2026-09-09T06:21:53.296158Z","steps":["trace[1406804909] 'read index received' (duration: 7.28135ms)","trace[1406804909] 'applied index is now lower than readState.Index' (duration: 222.036476ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:21:53.29642Z","caller":"traceutil/trace.go:171","msg":"trace[1986232792] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"148.017584ms","start":"2026-09-09T06:21:53.148389Z","end":"2026-09-09T06:21:53.296407Z","steps":["trace[1986232792] 'process raft request' (duration: 147.592535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:53.296433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.575531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-w7n52\" ","response":"range_response_count:1 size:5855"} {"level":"info","ts":"2026-09-09T06:21:53.296615Z","caller":"traceutil/trace.go:171","msg":"trace[1751827744] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-w7n52; range_end:; response_count:1; response_revision:1967; }","duration":"229.784046ms","start":"2026-09-09T06:21:53.066796Z","end":"2026-09-09T06:21:53.29658Z","steps":["trace[1751827744] 'agreement among raft nodes before linearized reading' (duration: 229.50005ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:53.341303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.871614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:21:53.341386Z","caller":"traceutil/trace.go:171","msg":"trace[1277702007] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1969; }","duration":"198.970666ms","start":"2026-09-09T06:21:53.142393Z","end":"2026-09-09T06:21:53.341364Z","steps":["trace[1277702007] 'agreement among raft nodes before linearized reading' (duration: 198.455415ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:53.465991Z","caller":"traceutil/trace.go:171","msg":"trace[737377853] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"159.491466ms","start":"2026-09-09T06:21:53.306475Z","end":"2026-09-09T06:21:53.465966Z","steps":["trace[737377853] 'process raft request' (duration: 159.309072ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:56.870098Z","caller":"traceutil/trace.go:171","msg":"trace[1950123855] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2069; }","duration":"188.796312ms","start":"2026-09-09T06:21:56.681268Z","end":"2026-09-09T06:21:56.870064Z","steps":["trace[1950123855] 'read index received' (duration: 188.514556ms)","trace[1950123855] 'applied index is now lower than readState.Index' (duration: 280.426µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:21:56.870226Z","caller":"traceutil/trace.go:171","msg":"trace[109496783] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"296.721231ms","start":"2026-09-09T06:21:56.573461Z","end":"2026-09-09T06:21:56.870182Z","steps":["trace[109496783] 'process raft request' (duration: 296.401504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:56.870528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.231152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T06:21:56.870578Z","caller":"traceutil/trace.go:171","msg":"trace[164496319] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1996; }","duration":"189.366335ms","start":"2026-09-09T06:21:56.681198Z","end":"2026-09-09T06:21:56.870564Z","steps":["trace[164496319] 'agreement among raft nodes before linearized reading' (duration: 188.996897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:22:29.613643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.335826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:22:29.613751Z","caller":"traceutil/trace.go:171","msg":"trace[569158669] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"141.078291ms","start":"2026-09-09T06:22:29.472652Z","end":"2026-09-09T06:22:29.61373Z","steps":["trace[569158669] 'process raft request' (duration: 19.570101ms)","trace[569158669] 'compare' (duration: 121.111001ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:22:30.195269Z","caller":"traceutil/trace.go:171","msg":"trace[1682167416] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"129.192945ms","start":"2026-09-09T06:22:30.066055Z","end":"2026-09-09T06:22:30.195248Z","steps":["trace[1682167416] 'process raft request' (duration: 129.057292ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:22:35.874315Z","caller":"traceutil/trace.go:171","msg":"trace[2018537846] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"150.970045ms","start":"2026-09-09T06:22:35.723279Z","end":"2026-09-09T06:22:35.874249Z","steps":["trace[2018537846] 'process raft request' (duration: 150.7372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:22:36.254224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.15005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:22:36.254337Z","caller":"traceutil/trace.go:171","msg":"trace[518402285] linearizableReadLoop","detail":"{readStateIndex:2295; appliedIndex:2294; }","duration":"368.371858ms","start":"2026-09-09T06:22:35.885946Z","end":"2026-09-09T06:22:36.254318Z","steps":["trace[518402285] 'read index received' (duration: 138.051744ms)","trace[518402285] 'applied index is now lower than readState.Index' (duration: 230.318764ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:22:36.254407Z","caller":"traceutil/trace.go:171","msg":"trace[553283075] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"441.141518ms","start":"2026-09-09T06:22:35.813245Z","end":"2026-09-09T06:22:36.254387Z","steps":["trace[553283075] 'process raft request' (duration: 210.721502ms)","trace[553283075] 'compare' (duration: 229.980277ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:22:36.254435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.48551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:22:36.254467Z","caller":"traceutil/trace.go:171","msg":"trace[357611894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2212; }","duration":"368.588012ms","start":"2026-09-09T06:22:35.885868Z","end":"2026-09-09T06:22:36.254456Z","steps":["trace[357611894] 'agreement among raft nodes before linearized reading' (duration: 368.52928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:22:36.254497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:22:35.81321Z","time spent":"441.239001ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:22:36.254504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:22:35.885847Z","time spent":"368.648154ms","remote":"127.0.0.1:54240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-09T06:23:11.458782Z","caller":"traceutil/trace.go:171","msg":"trace[121850027] transaction","detail":"{read_only:false; response_revision:2578; number_of_response:1; }","duration":"174.569807ms","start":"2026-09-09T06:23:11.284185Z","end":"2026-09-09T06:23:11.458755Z","steps":["trace[121850027] 'process raft request' (duration: 172.192094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:11.458816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.394219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-09T06:23:11.458978Z","caller":"traceutil/trace.go:171","msg":"trace[363090500] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2577; }","duration":"200.594944ms","start":"2026-09-09T06:23:11.258367Z","end":"2026-09-09T06:23:11.458962Z","steps":["trace[363090500] 'range keys from in-memory index tree' (duration: 200.257316ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:44.515408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.544103ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-09T06:23:44.51554Z","caller":"traceutil/trace.go:171","msg":"trace[267899475] linearizableReadLoop","detail":"{readStateIndex:2885; appliedIndex:2884; }","duration":"304.007043ms","start":"2026-09-09T06:23:44.211517Z","end":"2026-09-09T06:23:44.515524Z","steps":["trace[267899475] 'read index received' (duration: 85.261705ms)","trace[267899475] 'applied index is now lower than readState.Index' (duration: 218.743777ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:23:44.515892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.363321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-09T06:23:44.515999Z","caller":"traceutil/trace.go:171","msg":"trace[816378069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2779; }","duration":"304.511065ms","start":"2026-09-09T06:23:44.211468Z","end":"2026-09-09T06:23:44.515979Z","steps":["trace[816378069] 'agreement among raft nodes before linearized reading' (duration: 304.100424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:44.516037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:23:44.211424Z","time spent":"304.605157ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-09T06:23:49.755313Z","caller":"traceutil/trace.go:171","msg":"trace[422847542] transaction","detail":"{read_only:false; response_revision:2808; number_of_response:1; }","duration":"147.77741ms","start":"2026-09-09T06:23:49.607507Z","end":"2026-09-09T06:23:49.755285Z","steps":["trace[422847542] 'process raft request' (duration: 147.579135ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:49.755267Z","caller":"traceutil/trace.go:171","msg":"trace[1930265161] linearizableReadLoop","detail":"{readStateIndex:2915; appliedIndex:2914; }","duration":"110.971682ms","start":"2026-09-09T06:23:49.644268Z","end":"2026-09-09T06:23:49.755239Z","steps":["trace[1930265161] 'read index received' (duration: 110.736817ms)","trace[1930265161] 'applied index is now lower than readState.Index' (duration: 233.605µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:23:49.755617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.293238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T06:23:49.755748Z","caller":"traceutil/trace.go:171","msg":"trace[2018745383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2808; }","duration":"111.523354ms","start":"2026-09-09T06:23:49.64421Z","end":"2026-09-09T06:23:49.755733Z","steps":["trace[2018745383] 'agreement among raft nodes before linearized reading' (duration: 111.197356ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:20.085108Z","caller":"traceutil/trace.go:171","msg":"trace[147521343] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"238.033343ms","start":"2026-09-09T06:28:19.847047Z","end":"2026-09-09T06:28:20.08508Z","steps":["trace[147521343] 'process raft request' (duration: 157.949832ms)","trace[147521343] 'compare' (duration: 79.944818ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:28:20.172224Z","caller":"traceutil/trace.go:171","msg":"trace[1047070506] linearizableReadLoop","detail":"{readStateIndex:5340; appliedIndex:5338; }","duration":"301.549433ms","start":"2026-09-09T06:28:19.870648Z","end":"2026-09-09T06:28:20.172197Z","steps":["trace[1047070506] 'read index received' (duration: 134.542353ms)","trace[1047070506] 'applied index is now lower than readState.Index' (duration: 167.00605ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:28:20.172297Z","caller":"traceutil/trace.go:171","msg":"trace[980964605] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"322.081144ms","start":"2026-09-09T06:28:19.850168Z","end":"2026-09-09T06:28:20.172249Z","steps":["trace[980964605] 'process raft request' (duration: 321.879849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:28:20.172459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:28:19.850144Z","time spent":"322.233888ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3624,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T06:28:20.172639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.955062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T06:28:20.172701Z","caller":"traceutil/trace.go:171","msg":"trace[2109339103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5143; }","duration":"302.091145ms","start":"2026-09-09T06:28:19.870596Z","end":"2026-09-09T06:28:20.172687Z","steps":["trace[2109339103] 'agreement among raft nodes before linearized reading' (duration: 301.701517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:28:20.172771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:28:19.870574Z","time spent":"302.185538ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-09T06:28:20.248686Z","caller":"traceutil/trace.go:171","msg":"trace[315166612] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"154.007311ms","start":"2026-09-09T06:28:20.094629Z","end":"2026-09-09T06:28:20.248637Z","steps":["trace[315166612] 'process raft request' (duration: 117.689076ms)","trace[315166612] 'compare' (duration: 36.058259ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:28:20.248973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.600974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14497"} {"level":"info","ts":"2026-09-09T06:28:20.249093Z","caller":"traceutil/trace.go:171","msg":"trace[1088706991] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5145; }","duration":"154.740607ms","start":"2026-09-09T06:28:20.094304Z","end":"2026-09-09T06:28:20.249045Z","steps":["trace[1088706991] 'agreement among raft nodes before linearized reading' (duration: 154.476481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:28:20.249169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.875667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-09T06:28:20.249249Z","caller":"traceutil/trace.go:171","msg":"trace[1657785711] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5145; }","duration":"127.00645ms","start":"2026-09-09T06:28:20.122222Z","end":"2026-09-09T06:28:20.249228Z","steps":["trace[1657785711] 'agreement among raft nodes before linearized reading' (duration: 126.795314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:28:20.249273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.874041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:28:20.249375Z","caller":"traceutil/trace.go:171","msg":"trace[1366002695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5145; }","duration":"366.028705ms","start":"2026-09-09T06:28:19.883323Z","end":"2026-09-09T06:28:20.249352Z","steps":["trace[1366002695] 'agreement among raft nodes before linearized reading' (duration: 365.872011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:28:20.249423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:28:19.883299Z","time spent":"366.115937ms","remote":"127.0.0.1:54240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-09T06:28:35.356329Z","caller":"traceutil/trace.go:171","msg":"trace[1818568173] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"239.862004ms","start":"2026-09-09T06:28:35.116438Z","end":"2026-09-09T06:28:35.3563Z","steps":["trace[1818568173] 'process raft request' (duration: 239.536617ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:35.488364Z","caller":"traceutil/trace.go:171","msg":"trace[141907659] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"130.524921ms","start":"2026-09-09T06:28:35.357805Z","end":"2026-09-09T06:28:35.48833Z","steps":["trace[141907659] 'process raft request' (duration: 54.37429ms)","trace[141907659] 'compare' (duration: 75.861314ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:28:35.488411Z","caller":"traceutil/trace.go:171","msg":"trace[525556318] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"127.785808ms","start":"2026-09-09T06:28:35.3606Z","end":"2026-09-09T06:28:35.488385Z","steps":["trace[525556318] 'process raft request' (duration: 127.717857ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:35.488547Z","caller":"traceutil/trace.go:171","msg":"trace[713380688] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"128.001983ms","start":"2026-09-09T06:28:35.360519Z","end":"2026-09-09T06:28:35.488521Z","steps":["trace[713380688] 'process raft request' (duration: 127.744238ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:35.488566Z","caller":"traceutil/trace.go:171","msg":"trace[467584899] linearizableReadLoop","detail":"{readStateIndex:5625; appliedIndex:5621; }","duration":"117.516702ms","start":"2026-09-09T06:28:35.371021Z","end":"2026-09-09T06:28:35.488538Z","steps":["trace[467584899] 'read index received' (duration: 41.170546ms)","trace[467584899] 'applied index is now lower than readState.Index' (duration: 76.343736ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:28:35.488729Z","caller":"traceutil/trace.go:171","msg":"trace[2023679061] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"128.068975ms","start":"2026-09-09T06:28:35.360647Z","end":"2026-09-09T06:28:35.488716Z","steps":["trace[2023679061] 'process raft request' (duration: 127.703856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:28:35.488794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.761487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-668dm\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-09-09T06:28:35.489777Z","caller":"traceutil/trace.go:171","msg":"trace[1396596398] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-668dm; range_end:; response_count:1; response_revision:5418; }","duration":"117.851209ms","start":"2026-09-09T06:28:35.370984Z","end":"2026-09-09T06:28:35.488835Z","steps":["trace[1396596398] 'agreement among raft nodes before linearized reading' (duration: 117.647055ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:46.770499Z","caller":"traceutil/trace.go:171","msg":"trace[1114772505] transaction","detail":"{read_only:false; response_revision:5641; number_of_response:1; }","duration":"256.849964ms","start":"2026-09-09T06:28:46.513619Z","end":"2026-09-09T06:28:46.770469Z","steps":["trace[1114772505] 'process raft request' (duration: 256.68446ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:46.770802Z","caller":"traceutil/trace.go:171","msg":"trace[795471517] linearizableReadLoop","detail":"{readStateIndex:5852; appliedIndex:5852; }","duration":"206.057358ms","start":"2026-09-09T06:28:46.564715Z","end":"2026-09-09T06:28:46.770772Z","steps":["trace[795471517] 'read index received' (duration: 206.045517ms)","trace[795471517] 'applied index is now lower than readState.Index' (duration: 9.721µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:28:46.770929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.197742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-361dc251-ba59-4a94-9c0d-374433f97a0c\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-09-09T06:28:46.770974Z","caller":"traceutil/trace.go:171","msg":"trace[1630062585] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-361dc251-ba59-4a94-9c0d-374433f97a0c; range_end:; response_count:1; response_revision:5641; }","duration":"206.293083ms","start":"2026-09-09T06:28:46.564665Z","end":"2026-09-09T06:28:46.770958Z","steps":["trace[1630062585] 'agreement among raft nodes before linearized reading' (duration: 206.188971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:28:47.084224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.600338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:28:47.084665Z","caller":"traceutil/trace.go:171","msg":"trace[1939355358] linearizableReadLoop","detail":"{readStateIndex:5855; appliedIndex:5852; }","duration":"210.209842ms","start":"2026-09-09T06:28:46.874437Z","end":"2026-09-09T06:28:47.084647Z","steps":["trace[1939355358] 'read index received' (duration: 67.112283ms)","trace[1939355358] 'applied index is now lower than readState.Index' (duration: 143.096499ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:28:47.084654Z","caller":"traceutil/trace.go:171","msg":"trace[922738422] transaction","detail":"{read_only:false; response_revision:5642; number_of_response:1; }","duration":"295.3884ms","start":"2026-09-09T06:28:46.789223Z","end":"2026-09-09T06:28:47.084611Z","steps":["trace[922738422] 'process raft request' (duration: 152.313871ms)","trace[922738422] 'compare' (duration: 142.422544ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:28:47.084694Z","caller":"traceutil/trace.go:171","msg":"trace[136141950] transaction","detail":"{read_only:false; response_revision:5643; number_of_response:1; }","duration":"236.883696ms","start":"2026-09-09T06:28:46.847787Z","end":"2026-09-09T06:28:47.084671Z","steps":["trace[136141950] 'process raft request' (duration: 236.754353ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:47.084747Z","caller":"traceutil/trace.go:171","msg":"trace[1194489411] transaction","detail":"{read_only:false; response_revision:5644; number_of_response:1; }","duration":"236.901486ms","start":"2026-09-09T06:28:46.847834Z","end":"2026-09-09T06:28:47.084735Z","steps":["trace[1194489411] 'process raft request' (duration: 236.777623ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:28:47.084983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.5475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-78vsk\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-09T06:28:47.085015Z","caller":"traceutil/trace.go:171","msg":"trace[1860041061] range","detail":"{range_begin:/registry/pods/openstack/keepalived-78vsk; range_end:; response_count:1; response_revision:5644; }","duration":"210.620581ms","start":"2026-09-09T06:28:46.874386Z","end":"2026-09-09T06:28:47.085007Z","steps":["trace[1860041061] 'agreement among raft nodes before linearized reading' (duration: 210.473158ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:47.354462Z","caller":"traceutil/trace.go:171","msg":"trace[451498824] linearizableReadLoop","detail":"{readStateIndex:5856; appliedIndex:5855; }","duration":"269.734031ms","start":"2026-09-09T06:28:47.084704Z","end":"2026-09-09T06:28:47.354438Z","steps":["trace[451498824] 'read index received' (duration: 214.757096ms)","trace[451498824] 'applied index is now lower than readState.Index' (duration: 54.975855ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:28:47.35453Z","caller":"traceutil/trace.go:171","msg":"trace[1067705575] transaction","detail":"{read_only:false; response_revision:5645; number_of_response:1; }","duration":"360.046278ms","start":"2026-09-09T06:28:46.994442Z","end":"2026-09-09T06:28:47.354488Z","steps":["trace[1067705575] 'process raft request' (duration: 305.021523ms)","trace[1067705575] 'compare' (duration: 54.799319ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:28:47.354635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.218933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:28:47.354671Z","caller":"traceutil/trace.go:171","msg":"trace[716943999] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5645; }","duration":"337.318375ms","start":"2026-09-09T06:28:47.01734Z","end":"2026-09-09T06:28:47.354659Z","steps":["trace[716943999] 'agreement among raft nodes before linearized reading' (duration: 337.212533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:28:47.35469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:28:46.994419Z","time spent":"360.181902ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:28:47.354703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:28:47.017317Z","time spent":"337.376527ms","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:28:47.354859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.702271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:28:47.355006Z","caller":"traceutil/trace.go:171","msg":"trace[1221195412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5645; }","duration":"470.956287ms","start":"2026-09-09T06:28:46.884021Z","end":"2026-09-09T06:28:47.354977Z","steps":["trace[1221195412] 'agreement among raft nodes before linearized reading' (duration: 470.676021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:28:47.355063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:28:46.883997Z","time spent":"471.056519ms","remote":"127.0.0.1:54240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-09T06:28:47.357216Z","caller":"traceutil/trace.go:171","msg":"trace[758462881] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"261.443609ms","start":"2026-09-09T06:28:47.09575Z","end":"2026-09-09T06:28:47.357193Z","steps":["trace[758462881] 'process raft request' (duration: 261.390448ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:47.357506Z","caller":"traceutil/trace.go:171","msg":"trace[1058012512] transaction","detail":"{read_only:false; response_revision:5647; number_of_response:1; }","duration":"266.60897ms","start":"2026-09-09T06:28:47.090856Z","end":"2026-09-09T06:28:47.357465Z","steps":["trace[1058012512] 'process raft request' (duration: 266.129358ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:47.357543Z","caller":"traceutil/trace.go:171","msg":"trace[853101938] transaction","detail":"{read_only:false; response_revision:5648; number_of_response:1; }","duration":"266.171159ms","start":"2026-09-09T06:28:47.091351Z","end":"2026-09-09T06:28:47.357523Z","steps":["trace[853101938] 'process raft request' (duration: 265.682097ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:47.35753Z","caller":"traceutil/trace.go:171","msg":"trace[1848431917] transaction","detail":"{read_only:false; response_revision:5646; number_of_response:1; }","duration":"267.220283ms","start":"2026-09-09T06:28:47.090274Z","end":"2026-09-09T06:28:47.357494Z","steps":["trace[1848431917] 'process raft request' (duration: 266.604608ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:47.357693Z","caller":"traceutil/trace.go:171","msg":"trace[815110195] transaction","detail":"{read_only:false; response_revision:5649; number_of_response:1; }","duration":"265.934504ms","start":"2026-09-09T06:28:47.091737Z","end":"2026-09-09T06:28:47.357672Z","steps":["trace[815110195] 'process raft request' (duration: 265.32796ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:28:47.413888Z","caller":"traceutil/trace.go:171","msg":"trace[1121538723] transaction","detail":"{read_only:false; response_revision:5651; number_of_response:1; }","duration":"104.252737ms","start":"2026-09-09T06:28:47.309591Z","end":"2026-09-09T06:28:47.413843Z","steps":["trace[1121538723] 'process raft request' (duration: 104.090364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:28:47.414045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.276572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80389"} {"level":"info","ts":"2026-09-09T06:28:47.414122Z","caller":"traceutil/trace.go:171","msg":"trace[256243088] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5651; }","duration":"323.407385ms","start":"2026-09-09T06:28:47.0907Z","end":"2026-09-09T06:28:47.414107Z","steps":["trace[256243088] 'agreement among raft nodes before linearized reading' (duration: 323.121279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:28:47.414167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:28:47.090678Z","time spent":"323.479336ms","remote":"127.0.0.1:58002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":80412,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-09-09T06:29:15.491032Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2949} {"level":"info","ts":"2026-09-09T06:29:15.575201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2949,"took":"82.740542ms","hash":2442563924,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":32448512,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T06:29:15.575298Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2442563924,"revision":2949,"compact-revision":-1} {"level":"info","ts":"2026-09-09T06:34:15.497168Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5874} {"level":"info","ts":"2026-09-09T06:34:15.593848Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5874,"took":"95.198398ms","hash":827516467,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":25792512,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-09T06:34:15.593909Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":827516467,"revision":5874,"compact-revision":2949} {"level":"info","ts":"2026-09-09T06:35:18.722227Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022ab.wal"} {"level":"info","ts":"2026-09-09T06:35:22.767824Z","caller":"traceutil/trace.go:171","msg":"trace[402811155] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"104.168183ms","start":"2026-09-09T06:35:22.663629Z","end":"2026-09-09T06:35:22.767797Z","steps":["trace[402811155] 'process raft request' (duration: 104.005629ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:35:23.452437Z","caller":"traceutil/trace.go:171","msg":"trace[285146495] transaction","detail":"{read_only:false; response_revision:8586; number_of_response:1; }","duration":"143.085433ms","start":"2026-09-09T06:35:23.309327Z","end":"2026-09-09T06:35:23.452413Z","steps":["trace[285146495] 'process raft request' (duration: 142.93931ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:35:24.019988Z","caller":"traceutil/trace.go:171","msg":"trace[1079407122] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"108.137174ms","start":"2026-09-09T06:35:23.911806Z","end":"2026-09-09T06:35:24.019944Z","steps":["trace[1079407122] 'process raft request' (duration: 73.643185ms)","trace[1079407122] 'compare' (duration: 34.276664ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:35:53.223564Z","caller":"traceutil/trace.go:171","msg":"trace[845459812] linearizableReadLoop","detail":"{readStateIndex:9115; appliedIndex:9114; }","duration":"442.046023ms","start":"2026-09-09T06:35:52.781494Z","end":"2026-09-09T06:35:53.22354Z","steps":["trace[845459812] 'read index received' (duration: 441.887909ms)","trace[845459812] 'applied index is now lower than readState.Index' (duration: 157.104µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:35:53.223693Z","caller":"traceutil/trace.go:171","msg":"trace[662634475] transaction","detail":"{read_only:false; response_revision:8798; number_of_response:1; }","duration":"551.224211ms","start":"2026-09-09T06:35:52.672435Z","end":"2026-09-09T06:35:53.223659Z","steps":["trace[662634475] 'process raft request' (duration: 550.936484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:35:53.223813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.276698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-09T06:35:53.223857Z","caller":"traceutil/trace.go:171","msg":"trace[1983751956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8798; }","duration":"442.392501ms","start":"2026-09-09T06:35:52.781451Z","end":"2026-09-09T06:35:53.223844Z","steps":["trace[1983751956] 'agreement among raft nodes before linearized reading' (duration: 442.204917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:35:53.223861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:35:52.67242Z","time spent":"551.352224ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:35:53.223891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:35:52.781433Z","time spent":"442.449512ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-09T06:35:53.279928Z","caller":"traceutil/trace.go:171","msg":"trace[2085304645] transaction","detail":"{read_only:false; response_revision:8799; number_of_response:1; }","duration":"351.535022ms","start":"2026-09-09T06:35:52.928359Z","end":"2026-09-09T06:35:53.279894Z","steps":["trace[2085304645] 'process raft request' (duration: 351.286846ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:35:53.280005Z","caller":"traceutil/trace.go:171","msg":"trace[565943650] transaction","detail":"{read_only:false; response_revision:8800; number_of_response:1; }","duration":"347.919859ms","start":"2026-09-09T06:35:52.932056Z","end":"2026-09-09T06:35:53.279976Z","steps":["trace[565943650] 'process raft request' (duration: 347.701064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:35:53.279959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.306263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:35:53.280299Z","caller":"traceutil/trace.go:171","msg":"trace[1738996206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8800; }","duration":"395.757204ms","start":"2026-09-09T06:35:52.884523Z","end":"2026-09-09T06:35:53.28028Z","steps":["trace[1738996206] 'agreement among raft nodes before linearized reading' (duration: 395.300493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:35:53.280112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:35:52.928336Z","time spent":"351.686896ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:35:53.280465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:35:52.884503Z","time spent":"395.948488ms","remote":"127.0.0.1:54240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-09T06:35:53.280132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:35:52.932044Z","time spent":"348.015741ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:35:56.185576Z","caller":"traceutil/trace.go:171","msg":"trace[39651314] transaction","detail":"{read_only:false; response_revision:8813; number_of_response:1; }","duration":"235.710892ms","start":"2026-09-09T06:35:55.949841Z","end":"2026-09-09T06:35:56.185552Z","steps":["trace[39651314] 'process raft request' (duration: 235.5853ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:35:56.185839Z","caller":"traceutil/trace.go:171","msg":"trace[1431705119] linearizableReadLoop","detail":"{readStateIndex:9131; appliedIndex:9131; }","duration":"234.165388ms","start":"2026-09-09T06:35:55.951636Z","end":"2026-09-09T06:35:56.185801Z","steps":["trace[1431705119] 'read index received' (duration: 234.130587ms)","trace[1431705119] 'applied index is now lower than readState.Index' (duration: 31.671µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:35:56.186139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.474835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T06:35:56.186194Z","caller":"traceutil/trace.go:171","msg":"trace[1916476958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8813; }","duration":"234.607458ms","start":"2026-09-09T06:35:55.951572Z","end":"2026-09-09T06:35:56.18618Z","steps":["trace[1916476958] 'agreement among raft nodes before linearized reading' (duration: 234.395024ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:35:56.31181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.573739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:35:56.312021Z","caller":"traceutil/trace.go:171","msg":"trace[1124857931] linearizableReadLoop","detail":"{readStateIndex:9132; appliedIndex:9131; }","duration":"126.079694ms","start":"2026-09-09T06:35:56.185927Z","end":"2026-09-09T06:35:56.312007Z","steps":["trace[1124857931] 'read index received' (duration: 22.269219ms)","trace[1124857931] 'applied index is now lower than readState.Index' (duration: 103.808935ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:35:56.312085Z","caller":"traceutil/trace.go:171","msg":"trace[1833259763] transaction","detail":"{read_only:false; response_revision:8815; number_of_response:1; }","duration":"121.675734ms","start":"2026-09-09T06:35:56.190236Z","end":"2026-09-09T06:35:56.311912Z","steps":["trace[1833259763] 'process raft request' (duration: 121.643523ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:35:56.312175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.06572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:35:56.312154Z","caller":"traceutil/trace.go:171","msg":"trace[1398065] transaction","detail":"{read_only:false; response_revision:8814; number_of_response:1; }","duration":"186.998138ms","start":"2026-09-09T06:35:56.125123Z","end":"2026-09-09T06:35:56.312121Z","steps":["trace[1398065] 'process raft request' (duration: 83.04097ms)","trace[1398065] 'compare' (duration: 103.495507ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:35:56.312207Z","caller":"traceutil/trace.go:171","msg":"trace[1729413962] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8815; }","duration":"354.129492ms","start":"2026-09-09T06:35:55.958067Z","end":"2026-09-09T06:35:56.312196Z","steps":["trace[1729413962] 'agreement among raft nodes before linearized reading' (duration: 354.05406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:35:56.312229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:35:55.958051Z","time spent":"354.171803ms","remote":"127.0.0.1:48680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:35:56.312242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.16907ms","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:5"} {"level":"info","ts":"2026-09-09T06:35:56.312331Z","caller":"traceutil/trace.go:171","msg":"trace[426892046] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:8815; }","duration":"176.307574ms","start":"2026-09-09T06:35:56.136003Z","end":"2026-09-09T06:35:56.31231Z","steps":["trace[426892046] 'agreement among raft nodes before linearized reading' (duration: 176.12706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:35:56.312654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.093142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-09T06:35:56.312679Z","caller":"traceutil/trace.go:171","msg":"trace[1929496456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8815; }","duration":"313.153345ms","start":"2026-09-09T06:35:55.99952Z","end":"2026-09-09T06:35:56.312673Z","steps":["trace[1929496456] 'agreement among raft nodes before linearized reading' (duration: 313.053172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:35:56.312693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:35:55.9995Z","time spent":"313.189615ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-09T06:35:56.312831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.100831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-78vsk\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-09T06:35:56.312906Z","caller":"traceutil/trace.go:171","msg":"trace[1570277907] range","detail":"{range_begin:/registry/pods/openstack/keepalived-78vsk; range_end:; response_count:1; response_revision:8815; }","duration":"354.239944ms","start":"2026-09-09T06:35:55.958649Z","end":"2026-09-09T06:35:56.312889Z","steps":["trace[1570277907] 'agreement among raft nodes before linearized reading' (duration: 353.981629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:35:56.31295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:35:55.958637Z","time spent":"354.300875ms","remote":"127.0.0.1:58002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-78vsk\" "} {"level":"warn","ts":"2026-09-09T06:36:04.476067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.64544ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-09T06:36:04.476261Z","caller":"traceutil/trace.go:171","msg":"trace[653900498] linearizableReadLoop","detail":"{readStateIndex:9178; appliedIndex:9177; }","duration":"248.823623ms","start":"2026-09-09T06:36:04.227423Z","end":"2026-09-09T06:36:04.476246Z","steps":["trace[653900498] 'read index received' (duration: 58.911µs)","trace[653900498] 'applied index is now lower than readState.Index' (duration: 248.763572ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:36:04.476412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.983556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-09T06:36:04.476448Z","caller":"traceutil/trace.go:171","msg":"trace[747673612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8857; }","duration":"249.072588ms","start":"2026-09-09T06:36:04.227368Z","end":"2026-09-09T06:36:04.476441Z","steps":["trace[747673612] 'agreement among raft nodes before linearized reading' (duration: 248.962686ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:36:04.476635Z","caller":"traceutil/trace.go:171","msg":"trace[1536312453] transaction","detail":"{read_only:false; response_revision:8858; number_of_response:1; }","duration":"131.383846ms","start":"2026-09-09T06:36:04.345222Z","end":"2026-09-09T06:36:04.476606Z","steps":["trace[1536312453] 'process raft request' (duration: 130.947346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:36:04.518713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.037358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-78vsk\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-09-09T06:36:04.518736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.123601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-09T06:36:04.518768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.81405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T06:36:04.518817Z","caller":"traceutil/trace.go:171","msg":"trace[2073054111] range","detail":"{range_begin:/registry/pods/openstack/keepalived-78vsk; range_end:; response_count:1; response_revision:8858; }","duration":"145.14278ms","start":"2026-09-09T06:36:04.373632Z","end":"2026-09-09T06:36:04.518774Z","steps":["trace[2073054111] 'agreement among raft nodes before linearized reading' (duration: 102.974656ms)","trace[2073054111] 'range keys from in-memory index tree' (duration: 41.9686ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:36:04.518839Z","caller":"traceutil/trace.go:171","msg":"trace[990058386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8858; }","duration":"145.276263ms","start":"2026-09-09T06:36:04.373538Z","end":"2026-09-09T06:36:04.518815Z","steps":["trace[990058386] 'agreement among raft nodes before linearized reading' (duration: 103.080338ms)","trace[990058386] 'range keys from in-memory index tree' (duration: 41.95377ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:36:04.518873Z","caller":"traceutil/trace.go:171","msg":"trace[563301535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8858; }","duration":"171.976244ms","start":"2026-09-09T06:36:04.346874Z","end":"2026-09-09T06:36:04.51885Z","steps":["trace[563301535] 'agreement among raft nodes before linearized reading' (duration: 129.712917ms)","trace[563301535] 'range keys from in-memory index tree' (duration: 42.010511ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:36:05.484022Z","caller":"traceutil/trace.go:171","msg":"trace[865160339] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"118.725455ms","start":"2026-09-09T06:36:05.365271Z","end":"2026-09-09T06:36:05.483996Z","steps":["trace[865160339] 'process raft request' (duration: 118.558501ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:36:05.489261Z","caller":"traceutil/trace.go:171","msg":"trace[1856858119] transaction","detail":"{read_only:false; response_revision:8864; number_of_response:1; }","duration":"110.679922ms","start":"2026-09-09T06:36:05.37856Z","end":"2026-09-09T06:36:05.48924Z","steps":["trace[1856858119] 'process raft request' (duration: 110.346785ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:36:19.383466Z","caller":"traceutil/trace.go:171","msg":"trace[1809707104] transaction","detail":"{read_only:false; response_revision:9013; number_of_response:1; }","duration":"117.456837ms","start":"2026-09-09T06:36:19.265957Z","end":"2026-09-09T06:36:19.383414Z","steps":["trace[1809707104] 'process raft request' (duration: 117.315554ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:23.773917Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9bd37ed2859641e3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T06:37:23.780199Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-09T06:37:23.78031Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-09T06:37:35.928606Z","caller":"traceutil/trace.go:171","msg":"trace[1893764692] transaction","detail":"{read_only:false; response_revision:9726; number_of_response:1; }","duration":"119.095214ms","start":"2026-09-09T06:37:35.809482Z","end":"2026-09-09T06:37:35.928578Z","steps":["trace[1893764692] 'process raft request' (duration: 76.4957ms)","trace[1893764692] 'compare' (duration: 42.41019ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:36.145234Z","caller":"traceutil/trace.go:171","msg":"trace[1632545389] linearizableReadLoop","detail":"{readStateIndex:10075; appliedIndex:10074; }","duration":"116.941826ms","start":"2026-09-09T06:37:36.028006Z","end":"2026-09-09T06:37:36.144948Z","steps":["trace[1632545389] 'read index received' (duration: 99.458056ms)","trace[1632545389] 'applied index is now lower than readState.Index' (duration: 17.48197ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:36.145286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.233893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:36.145311Z","caller":"traceutil/trace.go:171","msg":"trace[54984562] transaction","detail":"{read_only:false; response_revision:9729; number_of_response:1; }","duration":"126.393211ms","start":"2026-09-09T06:37:36.018695Z","end":"2026-09-09T06:37:36.145089Z","steps":["trace[54984562] 'process raft request' (duration: 108.783108ms)","trace[54984562] 'compare' (duration: 16.98186ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:36.14538Z","caller":"traceutil/trace.go:171","msg":"trace[2009766527] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:9729; }","duration":"117.387905ms","start":"2026-09-09T06:37:36.027971Z","end":"2026-09-09T06:37:36.145359Z","steps":["trace[2009766527] 'agreement among raft nodes before linearized reading' (duration: 117.210672ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:39:15.510063Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8027} {"level":"info","ts":"2026-09-09T06:39:15.578176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8027,"took":"65.412183ms","hash":2463177827,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":28184576,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T06:39:15.578275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2463177827,"revision":8027,"compact-revision":5874} {"level":"info","ts":"2026-09-09T06:43:14.867547Z","caller":"traceutil/trace.go:171","msg":"trace[429768953] linearizableReadLoop","detail":"{readStateIndex:12586; appliedIndex:12585; }","duration":"134.40781ms","start":"2026-09-09T06:43:14.733111Z","end":"2026-09-09T06:43:14.867519Z","steps":["trace[429768953] 'read index received' (duration: 134.200935ms)","trace[429768953] 'applied index is now lower than readState.Index' (duration: 188.305µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:43:14.867804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.671916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-09T06:43:14.867922Z","caller":"traceutil/trace.go:171","msg":"trace[1619947799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12162; }","duration":"134.821519ms","start":"2026-09-09T06:43:14.733083Z","end":"2026-09-09T06:43:14.867905Z","steps":["trace[1619947799] 'agreement among raft nodes before linearized reading' (duration: 134.554853ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:43:14.867651Z","caller":"traceutil/trace.go:171","msg":"trace[95500635] transaction","detail":"{read_only:false; response_revision:12162; number_of_response:1; }","duration":"135.335612ms","start":"2026-09-09T06:43:14.732271Z","end":"2026-09-09T06:43:14.867607Z","steps":["trace[95500635] 'process raft request' (duration: 135.032965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:43:14.86842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.985164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T06:43:14.868646Z","caller":"traceutil/trace.go:171","msg":"trace[1419533671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12162; }","duration":"132.26619ms","start":"2026-09-09T06:43:14.736361Z","end":"2026-09-09T06:43:14.868628Z","steps":["trace[1419533671] 'agreement among raft nodes before linearized reading' (duration: 131.907222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:43:15.961533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.345513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-09T06:43:15.961645Z","caller":"traceutil/trace.go:171","msg":"trace[128805621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12168; }","duration":"112.510877ms","start":"2026-09-09T06:43:15.849104Z","end":"2026-09-09T06:43:15.961615Z","steps":["trace[128805621] 'range keys from in-memory index tree' (duration: 112.140558ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:44:15.516839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10411} {"level":"info","ts":"2026-09-09T06:44:15.60925Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10411,"took":"90.442739ms","hash":1049706933,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":26603520,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-09T06:44:15.609321Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1049706933,"revision":10411,"compact-revision":8027} {"level":"info","ts":"2026-09-09T06:46:57.4957Z","caller":"traceutil/trace.go:171","msg":"trace[1595930408] transaction","detail":"{read_only:false; response_revision:13601; number_of_response:1; }","duration":"109.469019ms","start":"2026-09-09T06:46:57.386211Z","end":"2026-09-09T06:46:57.49568Z","steps":["trace[1595930408] 'process raft request' (duration: 109.409178ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:46:57.495792Z","caller":"traceutil/trace.go:171","msg":"trace[809875841] transaction","detail":"{read_only:false; response_revision:13600; number_of_response:1; }","duration":"126.578961ms","start":"2026-09-09T06:46:57.369172Z","end":"2026-09-09T06:46:57.495751Z","steps":["trace[809875841] 'process raft request' (duration: 91.525147ms)","trace[809875841] 'compare' (duration: 34.795078ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:49:15.523955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12590} {"level":"info","ts":"2026-09-09T06:49:15.608764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12590,"took":"82.401069ms","hash":1966478751,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":27893760,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T06:49:15.608863Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1966478751,"revision":12590,"compact-revision":10411} {"level":"info","ts":"2026-09-09T06:49:24.916471Z","caller":"traceutil/trace.go:171","msg":"trace[1115487629] transaction","detail":"{read_only:false; response_revision:14657; number_of_response:1; }","duration":"164.757258ms","start":"2026-09-09T06:49:24.751688Z","end":"2026-09-09T06:49:24.916445Z","steps":["trace[1115487629] 'process raft request' (duration: 164.570764ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:49:24.919105Z","caller":"traceutil/trace.go:171","msg":"trace[1927457935] transaction","detail":"{read_only:false; response_revision:14658; number_of_response:1; }","duration":"166.410125ms","start":"2026-09-09T06:49:24.752672Z","end":"2026-09-09T06:49:24.919082Z","steps":["trace[1927457935] 'process raft request' (duration: 166.264122ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:49:24.919629Z","caller":"traceutil/trace.go:171","msg":"trace[553478972] linearizableReadLoop","detail":"{readStateIndex:15171; appliedIndex:15170; }","duration":"114.363871ms","start":"2026-09-09T06:49:24.805245Z","end":"2026-09-09T06:49:24.919609Z","steps":["trace[553478972] 'read index received' (duration: 111.801492ms)","trace[553478972] 'applied index is now lower than readState.Index' (duration: 2.560959ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:49:24.919911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.037936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T06:49:24.919972Z","caller":"traceutil/trace.go:171","msg":"trace[981651658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14660; }","duration":"108.169751ms","start":"2026-09-09T06:49:24.811785Z","end":"2026-09-09T06:49:24.919955Z","steps":["trace[981651658] 'agreement among raft nodes before linearized reading' (duration: 107.950645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:49:24.919993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.73743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/placement-api-56bb694b47-lpd8m\" ","response":"range_response_count:1 size:8543"} {"level":"info","ts":"2026-09-09T06:49:24.920066Z","caller":"traceutil/trace.go:171","msg":"trace[855443414] range","detail":"{range_begin:/registry/pods/openstack/placement-api-56bb694b47-lpd8m; range_end:; response_count:1; response_revision:14660; }","duration":"114.851383ms","start":"2026-09-09T06:49:24.805193Z","end":"2026-09-09T06:49:24.920045Z","steps":["trace[855443414] 'agreement among raft nodes before linearized reading' (duration: 114.696468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:49:54.590363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.814529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-09-09T06:49:54.590455Z","caller":"traceutil/trace.go:171","msg":"trace[1375150796] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14815; }","duration":"106.933142ms","start":"2026-09-09T06:49:54.483486Z","end":"2026-09-09T06:49:54.590419Z","steps":["trace[1375150796] 'range keys from in-memory index tree' (duration: 106.653635ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:49:55.17506Z","caller":"traceutil/trace.go:171","msg":"trace[1407802103] transaction","detail":"{read_only:false; response_revision:14816; number_of_response:1; }","duration":"133.670544ms","start":"2026-09-09T06:49:55.041362Z","end":"2026-09-09T06:49:55.175032Z","steps":["trace[1407802103] 'process raft request' (duration: 133.437718ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:49:55.206084Z","caller":"traceutil/trace.go:171","msg":"trace[1407762807] transaction","detail":"{read_only:false; response_revision:14817; number_of_response:1; }","duration":"143.593232ms","start":"2026-09-09T06:49:55.062462Z","end":"2026-09-09T06:49:55.206055Z","steps":["trace[1407762807] 'process raft request' (duration: 137.159845ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:05.204561Z","caller":"traceutil/trace.go:171","msg":"trace[712571207] transaction","detail":"{read_only:false; response_revision:14884; number_of_response:1; }","duration":"325.675675ms","start":"2026-09-09T06:50:04.878862Z","end":"2026-09-09T06:50:05.204537Z","steps":["trace[712571207] 'process raft request' (duration: 325.515641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:05.204704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:50:04.878801Z","time spent":"325.832179ms","remote":"127.0.0.1:58002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:50:07.359244Z","caller":"traceutil/trace.go:171","msg":"trace[1355236956] transaction","detail":"{read_only:false; response_revision:14904; number_of_response:1; }","duration":"133.789847ms","start":"2026-09-09T06:50:07.225424Z","end":"2026-09-09T06:50:07.359214Z","steps":["trace[1355236956] 'process raft request' (duration: 133.645263ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:07.360677Z","caller":"traceutil/trace.go:171","msg":"trace[198113256] transaction","detail":"{read_only:false; response_revision:14905; number_of_response:1; }","duration":"106.678085ms","start":"2026-09-09T06:50:07.253986Z","end":"2026-09-09T06:50:07.360664Z","steps":["trace[198113256] 'process raft request' (duration: 106.494961ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:16.139694Z","caller":"traceutil/trace.go:171","msg":"trace[2081786589] transaction","detail":"{read_only:false; response_revision:15063; number_of_response:1; }","duration":"263.655943ms","start":"2026-09-09T06:50:15.876012Z","end":"2026-09-09T06:50:16.139668Z","steps":["trace[2081786589] 'process raft request' (duration: 237.443403ms)","trace[2081786589] 'compare' (duration: 25.714479ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:50:16.312787Z","caller":"traceutil/trace.go:171","msg":"trace[466157243] linearizableReadLoop","detail":"{readStateIndex:15590; appliedIndex:15588; }","duration":"427.214784ms","start":"2026-09-09T06:50:15.885547Z","end":"2026-09-09T06:50:16.312762Z","steps":["trace[466157243] 'read index received' (duration: 227.921295ms)","trace[466157243] 'applied index is now lower than readState.Index' (duration: 199.292419ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:50:16.312938Z","caller":"traceutil/trace.go:171","msg":"trace[662639080] transaction","detail":"{read_only:false; response_revision:15064; number_of_response:1; }","duration":"436.597599ms","start":"2026-09-09T06:50:15.876278Z","end":"2026-09-09T06:50:16.312876Z","steps":["trace[662639080] 'process raft request' (duration: 436.277732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:16.313054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.482399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:50:16.313105Z","caller":"traceutil/trace.go:171","msg":"trace[121978038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15064; }","duration":"427.580022ms","start":"2026-09-09T06:50:15.885513Z","end":"2026-09-09T06:50:16.313093Z","steps":["trace[121978038] 'agreement among raft nodes before linearized reading' (duration: 427.407828ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:16.313119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:50:15.87627Z","time spent":"436.756973ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:50:16.313159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:50:15.885497Z","time spent":"427.653903ms","remote":"127.0.0.1:54240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-09T06:50:16.313151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.355248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T06:50:16.313215Z","caller":"traceutil/trace.go:171","msg":"trace[471798417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15064; }","duration":"376.44952ms","start":"2026-09-09T06:50:15.936749Z","end":"2026-09-09T06:50:16.313199Z","steps":["trace[471798417] 'agreement among raft nodes before linearized reading' (duration: 376.285207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:16.313252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:50:15.936734Z","time spent":"376.511291ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-09T06:50:16.313245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.367156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-fc978744-xrqsn\" ","response":"range_response_count:1 size:7032"} {"level":"info","ts":"2026-09-09T06:50:16.313303Z","caller":"traceutil/trace.go:171","msg":"trace[1088485895] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-fc978744-xrqsn; range_end:; response_count:1; response_revision:15064; }","duration":"273.506699ms","start":"2026-09-09T06:50:16.039782Z","end":"2026-09-09T06:50:16.313289Z","steps":["trace[1088485895] 'agreement among raft nodes before linearized reading' (duration: 273.290645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:16.313391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.944055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:50:16.313543Z","caller":"traceutil/trace.go:171","msg":"trace[219813185] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:15064; }","duration":"238.207491ms","start":"2026-09-09T06:50:16.07531Z","end":"2026-09-09T06:50:16.313517Z","steps":["trace[219813185] 'agreement among raft nodes before linearized reading' (duration: 237.75305ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:16.460343Z","caller":"traceutil/trace.go:171","msg":"trace[487043539] linearizableReadLoop","detail":"{readStateIndex:15591; appliedIndex:15590; }","duration":"141.662927ms","start":"2026-09-09T06:50:16.318651Z","end":"2026-09-09T06:50:16.460314Z","steps":["trace[487043539] 'read index received' (duration: 140.475401ms)","trace[487043539] 'applied index is now lower than readState.Index' (duration: 1.186046ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:50:16.460411Z","caller":"traceutil/trace.go:171","msg":"trace[1679151826] transaction","detail":"{read_only:false; response_revision:15065; number_of_response:1; }","duration":"143.644272ms","start":"2026-09-09T06:50:16.316727Z","end":"2026-09-09T06:50:16.460371Z","steps":["trace[1679151826] 'process raft request' (duration: 142.414465ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:16.460501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.829392ms","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:5"} {"level":"info","ts":"2026-09-09T06:50:16.460552Z","caller":"traceutil/trace.go:171","msg":"trace[2079188862] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:15065; }","duration":"141.941755ms","start":"2026-09-09T06:50:16.318594Z","end":"2026-09-09T06:50:16.460536Z","steps":["trace[2079188862] 'agreement among raft nodes before linearized reading' (duration: 141.834221ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:16.467388Z","caller":"traceutil/trace.go:171","msg":"trace[493040202] transaction","detail":"{read_only:false; response_revision:15067; number_of_response:1; }","duration":"145.464534ms","start":"2026-09-09T06:50:16.321901Z","end":"2026-09-09T06:50:16.467366Z","steps":["trace[493040202] 'process raft request' (duration: 145.386802ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:16.467504Z","caller":"traceutil/trace.go:171","msg":"trace[922569386] transaction","detail":"{read_only:false; response_revision:15066; number_of_response:1; }","duration":"148.586047ms","start":"2026-09-09T06:50:16.318883Z","end":"2026-09-09T06:50:16.467469Z","steps":["trace[922569386] 'process raft request' (duration: 148.273429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:21.222428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.293769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-09-09T06:50:21.222598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.855004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-09-09T06:50:21.222673Z","caller":"traceutil/trace.go:171","msg":"trace[1480489250] transaction","detail":"{read_only:false; response_revision:15171; number_of_response:1; }","duration":"114.889173ms","start":"2026-09-09T06:50:21.107739Z","end":"2026-09-09T06:50:21.222629Z","steps":["trace[1480489250] 'process raft request' (duration: 11.34351ms)","trace[1480489250] 'compare' (duration: 103.109303ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:50:21.222732Z","caller":"traceutil/trace.go:171","msg":"trace[1553837493] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:15170; }","duration":"156.014917ms","start":"2026-09-09T06:50:21.066672Z","end":"2026-09-09T06:50:21.222687Z","steps":["trace[1553837493] 'range keys from in-memory index tree' (duration: 155.612068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:57.859956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.926209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-09T06:50:57.859989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.230831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-09T06:50:57.860061Z","caller":"traceutil/trace.go:171","msg":"trace[1725137300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15526; }","duration":"137.085133ms","start":"2026-09-09T06:50:57.722955Z","end":"2026-09-09T06:50:57.86004Z","steps":["trace[1725137300] 'range keys from in-memory index tree' (duration: 136.687764ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:57.860071Z","caller":"traceutil/trace.go:171","msg":"trace[437746138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15526; }","duration":"142.358304ms","start":"2026-09-09T06:50:57.717691Z","end":"2026-09-09T06:50:57.860049Z","steps":["trace[437746138] 'range keys from in-memory index tree' (duration: 141.967935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:57.860114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.441913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T06:50:57.860201Z","caller":"traceutil/trace.go:171","msg":"trace[863830926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15526; }","duration":"148.577456ms","start":"2026-09-09T06:50:57.711602Z","end":"2026-09-09T06:50:57.860179Z","steps":["trace[863830926] 'range keys from in-memory index tree' (duration: 148.269049ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:51:18.684548Z","caller":"traceutil/trace.go:171","msg":"trace[697929378] transaction","detail":"{read_only:false; response_revision:15938; number_of_response:1; }","duration":"139.190371ms","start":"2026-09-09T06:51:18.545326Z","end":"2026-09-09T06:51:18.684517Z","steps":["trace[697929378] 'process raft request' (duration: 138.809483ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:51:18.68595Z","caller":"traceutil/trace.go:171","msg":"trace[278874255] transaction","detail":"{read_only:false; response_revision:15939; number_of_response:1; }","duration":"107.209248ms","start":"2026-09-09T06:51:18.57872Z","end":"2026-09-09T06:51:18.685929Z","steps":["trace[278874255] 'process raft request' (duration: 107.025114ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:51:58.308881Z","caller":"traceutil/trace.go:171","msg":"trace[311165587] linearizableReadLoop","detail":"{readStateIndex:16717; appliedIndex:16716; }","duration":"108.544867ms","start":"2026-09-09T06:51:58.200304Z","end":"2026-09-09T06:51:58.308849Z","steps":["trace[311165587] 'read index received' (duration: 85.453129ms)","trace[311165587] 'applied index is now lower than readState.Index' (duration: 23.090308ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:51:58.309224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.899256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:51:58.309299Z","caller":"traceutil/trace.go:171","msg":"trace[397624994] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16158; }","duration":"109.028299ms","start":"2026-09-09T06:51:58.200251Z","end":"2026-09-09T06:51:58.309279Z","steps":["trace[397624994] 'agreement among raft nodes before linearized reading' (duration: 108.900996ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:51:58.309618Z","caller":"traceutil/trace.go:171","msg":"trace[947963435] transaction","detail":"{read_only:false; response_revision:16158; number_of_response:1; }","duration":"164.697976ms","start":"2026-09-09T06:51:58.144891Z","end":"2026-09-09T06:51:58.309589Z","steps":["trace[947963435] 'process raft request' (duration: 140.283776ms)","trace[947963435] 'compare' (duration: 23.466548ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:51:58.309663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.886386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:51:58.309715Z","caller":"traceutil/trace.go:171","msg":"trace[1247109996] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16158; }","duration":"108.960468ms","start":"2026-09-09T06:51:58.200738Z","end":"2026-09-09T06:51:58.309698Z","steps":["trace[1247109996] 'agreement among raft nodes before linearized reading' (duration: 108.883407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:51:58.309865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.484529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:51:58.309896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.986887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-09T06:51:58.309946Z","caller":"traceutil/trace.go:171","msg":"trace[1772478569] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16158; }","duration":"109.592792ms","start":"2026-09-09T06:51:58.200328Z","end":"2026-09-09T06:51:58.309921Z","steps":["trace[1772478569] 'agreement among raft nodes before linearized reading' (duration: 109.455089ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:51:58.310097Z","caller":"traceutil/trace.go:171","msg":"trace[498034682] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16158; }","duration":"109.812447ms","start":"2026-09-09T06:51:58.200266Z","end":"2026-09-09T06:51:58.310078Z","steps":["trace[498034682] 'agreement among raft nodes before linearized reading' (duration: 108.904466ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:51:58.613912Z","caller":"traceutil/trace.go:171","msg":"trace[391710286] linearizableReadLoop","detail":"{readStateIndex:16718; appliedIndex:16717; }","duration":"203.321431ms","start":"2026-09-09T06:51:58.410561Z","end":"2026-09-09T06:51:58.613882Z","steps":["trace[391710286] 'read index received' (duration: 203.205929ms)","trace[391710286] 'applied index is now lower than readState.Index' (duration: 114.472µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:51:58.61397Z","caller":"traceutil/trace.go:171","msg":"trace[1208543632] transaction","detail":"{read_only:false; response_revision:16159; number_of_response:1; }","duration":"219.527452ms","start":"2026-09-09T06:51:58.394399Z","end":"2026-09-09T06:51:58.613927Z","steps":["trace[1208543632] 'process raft request' (duration: 219.301307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:51:58.614148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.566397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-09T06:51:58.614189Z","caller":"traceutil/trace.go:171","msg":"trace[826335487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16159; }","duration":"203.653048ms","start":"2026-09-09T06:51:58.410522Z","end":"2026-09-09T06:51:58.614175Z","steps":["trace[826335487] 'agreement among raft nodes before linearized reading' (duration: 203.495954ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:51:58.628354Z","caller":"traceutil/trace.go:171","msg":"trace[258123293] transaction","detail":"{read_only:false; response_revision:16160; number_of_response:1; }","duration":"176.175989ms","start":"2026-09-09T06:51:58.452147Z","end":"2026-09-09T06:51:58.628323Z","steps":["trace[258123293] 'process raft request' (duration: 175.961464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:51:58.628924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.6273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T06:51:58.629027Z","caller":"traceutil/trace.go:171","msg":"trace[2018506455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16160; }","duration":"217.755272ms","start":"2026-09-09T06:51:58.411246Z","end":"2026-09-09T06:51:58.629001Z","steps":["trace[2018506455] 'agreement among raft nodes before linearized reading' (duration: 217.017905ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:12.234464Z","caller":"traceutil/trace.go:171","msg":"trace[1763794801] transaction","detail":"{read_only:false; response_revision:16230; number_of_response:1; }","duration":"133.03326ms","start":"2026-09-09T06:52:12.101397Z","end":"2026-09-09T06:52:12.23443Z","steps":["trace[1763794801] 'process raft request' (duration: 132.320864ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:12.234455Z","caller":"traceutil/trace.go:171","msg":"trace[1407381964] linearizableReadLoop","detail":"{readStateIndex:16792; appliedIndex:16791; }","duration":"131.500315ms","start":"2026-09-09T06:52:12.102917Z","end":"2026-09-09T06:52:12.234417Z","steps":["trace[1407381964] 'read index received' (duration: 130.85814ms)","trace[1407381964] 'applied index is now lower than readState.Index' (duration: 640.925µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:52:12.234674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.74025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:52:12.234723Z","caller":"traceutil/trace.go:171","msg":"trace[1165922096] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16230; }","duration":"131.830412ms","start":"2026-09-09T06:52:12.102877Z","end":"2026-09-09T06:52:12.234707Z","steps":["trace[1165922096] 'agreement among raft nodes before linearized reading' (duration: 131.71801ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:12.477839Z","caller":"traceutil/trace.go:171","msg":"trace[1865980341] linearizableReadLoop","detail":"{readStateIndex:16794; appliedIndex:16792; }","duration":"243.237196ms","start":"2026-09-09T06:52:12.234561Z","end":"2026-09-09T06:52:12.477798Z","steps":["trace[1865980341] 'read index received' (duration: 243.001891ms)","trace[1865980341] 'applied index is now lower than readState.Index' (duration: 234.445µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:52:12.477927Z","caller":"traceutil/trace.go:171","msg":"trace[918347984] transaction","detail":"{read_only:false; response_revision:16232; number_of_response:1; }","duration":"360.592748ms","start":"2026-09-09T06:52:12.117311Z","end":"2026-09-09T06:52:12.477903Z","steps":["trace[918347984] 'process raft request' (duration: 360.447374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.478144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.562101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-09-09T06:52:12.478177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.448017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:52:12.478194Z","caller":"traceutil/trace.go:171","msg":"trace[1180585297] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16232; }","duration":"372.643273ms","start":"2026-09-09T06:52:12.105537Z","end":"2026-09-09T06:52:12.478181Z","steps":["trace[1180585297] 'agreement among raft nodes before linearized reading' (duration: 372.463359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.478121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:12.117298Z","time spent":"360.675309ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:52:12.47824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:12.105523Z","time spent":"372.708704ms","remote":"127.0.0.1:58036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5544,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-09-09T06:52:12.478241Z","caller":"traceutil/trace.go:171","msg":"trace[333761528] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16232; }","duration":"132.54935ms","start":"2026-09-09T06:52:12.345674Z","end":"2026-09-09T06:52:12.478223Z","steps":["trace[333761528] 'agreement among raft nodes before linearized reading' (duration: 132.447907ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.478324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.499276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:52:12.478374Z","caller":"traceutil/trace.go:171","msg":"trace[704980693] transaction","detail":"{read_only:false; response_revision:16231; number_of_response:1; }","duration":"365.53079ms","start":"2026-09-09T06:52:12.112826Z","end":"2026-09-09T06:52:12.478356Z","steps":["trace[704980693] 'process raft request' (duration: 364.795973ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.478379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.880033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:52:12.478385Z","caller":"traceutil/trace.go:171","msg":"trace[442152047] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:16232; }","duration":"354.597539ms","start":"2026-09-09T06:52:12.123771Z","end":"2026-09-09T06:52:12.478368Z","steps":["trace[442152047] 'agreement among raft nodes before linearized reading' (duration: 354.377634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.478179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.268435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-09-09T06:52:12.478426Z","caller":"traceutil/trace.go:171","msg":"trace[1046271332] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16232; }","duration":"131.943775ms","start":"2026-09-09T06:52:12.346469Z","end":"2026-09-09T06:52:12.478413Z","steps":["trace[1046271332] 'agreement among raft nodes before linearized reading' (duration: 131.876973ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.478429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.58148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-09-09T06:52:12.478451Z","caller":"traceutil/trace.go:171","msg":"trace[351761879] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16232; }","duration":"345.614463ms","start":"2026-09-09T06:52:12.132826Z","end":"2026-09-09T06:52:12.47844Z","steps":["trace[351761879] 'agreement among raft nodes before linearized reading' (duration: 345.098182ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.47845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:12.123754Z","time spent":"354.67601ms","remote":"127.0.0.1:57928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":122,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:52:12.47846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:12.112792Z","time spent":"365.620422ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:52:12.478486Z","caller":"traceutil/trace.go:171","msg":"trace[968801413] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16232; }","duration":"129.674862ms","start":"2026-09-09T06:52:12.348794Z","end":"2026-09-09T06:52:12.478468Z","steps":["trace[968801413] 'agreement among raft nodes before linearized reading' (duration: 129.486518ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.478505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:12.132799Z","time spent":"345.674954ms","remote":"127.0.0.1:57990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-09-09T06:52:12.478506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.62842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:52:12.478578Z","caller":"traceutil/trace.go:171","msg":"trace[1551204836] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16232; }","duration":"132.773613ms","start":"2026-09-09T06:52:12.345784Z","end":"2026-09-09T06:52:12.478558Z","steps":["trace[1551204836] 'agreement among raft nodes before linearized reading' (duration: 132.62281ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.478592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.286929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:52:12.478632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.972031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:52:12.478632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.108815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-09T06:52:12.478639Z","caller":"traceutil/trace.go:171","msg":"trace[6415298] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16232; }","duration":"124.3517ms","start":"2026-09-09T06:52:12.354273Z","end":"2026-09-09T06:52:12.478625Z","steps":["trace[6415298] 'agreement among raft nodes before linearized reading' (duration: 124.276848ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:12.478666Z","caller":"traceutil/trace.go:171","msg":"trace[1887346022] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16232; }","duration":"127.022511ms","start":"2026-09-09T06:52:12.351631Z","end":"2026-09-09T06:52:12.478654Z","steps":["trace[1887346022] 'agreement among raft nodes before linearized reading' (duration: 126.97467ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.478522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.278639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-09T06:52:12.478683Z","caller":"traceutil/trace.go:171","msg":"trace[871786502] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16232; }","duration":"124.173926ms","start":"2026-09-09T06:52:12.354493Z","end":"2026-09-09T06:52:12.478667Z","steps":["trace[871786502] 'agreement among raft nodes before linearized reading' (duration: 124.055614ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:12.47872Z","caller":"traceutil/trace.go:171","msg":"trace[1430780088] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16232; }","duration":"107.492983ms","start":"2026-09-09T06:52:12.371209Z","end":"2026-09-09T06:52:12.478702Z","steps":["trace[1430780088] 'agreement among raft nodes before linearized reading' (duration: 107.202687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.478346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.325015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:52:12.47876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.589481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:52:12.478797Z","caller":"traceutil/trace.go:171","msg":"trace[255387376] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16232; }","duration":"126.821528ms","start":"2026-09-09T06:52:12.351959Z","end":"2026-09-09T06:52:12.478781Z","steps":["trace[255387376] 'agreement among raft nodes before linearized reading' (duration: 126.258115ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:12.478793Z","caller":"traceutil/trace.go:171","msg":"trace[1650653060] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16232; }","duration":"129.678713ms","start":"2026-09-09T06:52:12.349103Z","end":"2026-09-09T06:52:12.478782Z","steps":["trace[1650653060] 'agreement among raft nodes before linearized reading' (duration: 129.637361ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.478907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.649878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-09T06:52:12.47894Z","caller":"traceutil/trace.go:171","msg":"trace[1954666395] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16232; }","duration":"124.704038ms","start":"2026-09-09T06:52:12.354226Z","end":"2026-09-09T06:52:12.478931Z","steps":["trace[1954666395] 'agreement among raft nodes before linearized reading' (duration: 124.611756ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.982203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.454399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-78vsk\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-09T06:52:12.982302Z","caller":"traceutil/trace.go:171","msg":"trace[353764975] range","detail":"{range_begin:/registry/pods/openstack/keepalived-78vsk; range_end:; response_count:1; response_revision:16235; }","duration":"157.582882ms","start":"2026-09-09T06:52:12.824693Z","end":"2026-09-09T06:52:12.982276Z","steps":["trace[353764975] 'range keys from in-memory index tree' (duration: 156.916607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.982533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.370647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769803"} {"level":"info","ts":"2026-09-09T06:52:12.982622Z","caller":"traceutil/trace.go:171","msg":"trace[1011177265] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16235; }","duration":"157.49473ms","start":"2026-09-09T06:52:12.825099Z","end":"2026-09-09T06:52:12.982594Z","steps":["trace[1011177265] 'range keys from in-memory index tree' (duration: 156.600831ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:38.54758Z","caller":"traceutil/trace.go:171","msg":"trace[35849907] transaction","detail":"{read_only:false; response_revision:16367; number_of_response:1; }","duration":"360.990776ms","start":"2026-09-09T06:52:38.186568Z","end":"2026-09-09T06:52:38.547559Z","steps":["trace[35849907] 'process raft request' (duration: 360.839273ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.547714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:38.186545Z","time spent":"361.102078ms","remote":"127.0.0.1:58002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7320,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:52:38.692088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.715686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:52:38.692426Z","caller":"traceutil/trace.go:171","msg":"trace[2022420991] linearizableReadLoop","detail":"{readStateIndex:16936; appliedIndex:16935; }","duration":"443.283712ms","start":"2026-09-09T06:52:38.249126Z","end":"2026-09-09T06:52:38.69241Z","steps":["trace[2022420991] 'read index received' (duration: 298.422321ms)","trace[2022420991] 'applied index is now lower than readState.Index' (duration: 144.859891ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:52:38.692449Z","caller":"traceutil/trace.go:171","msg":"trace[1243575085] transaction","detail":"{read_only:false; response_revision:16368; number_of_response:1; }","duration":"498.341164ms","start":"2026-09-09T06:52:38.194066Z","end":"2026-09-09T06:52:38.692407Z","steps":["trace[1243575085] 'process raft request' (duration: 391.236038ms)","trace[1243575085] 'compare' (duration: 106.632025ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:52:38.692515Z","caller":"traceutil/trace.go:171","msg":"trace[474081404] transaction","detail":"{read_only:false; response_revision:16369; number_of_response:1; }","duration":"352.044201ms","start":"2026-09-09T06:52:38.340445Z","end":"2026-09-09T06:52:38.692489Z","steps":["trace[474081404] 'process raft request' (duration: 351.715044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.692661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:38.340411Z","time spent":"352.184524ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:52:38.692659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:38.194036Z","time spent":"498.496858ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:52:38.692769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.619429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5hr46\" ","response":"range_response_count:1 size:20637"} {"level":"info","ts":"2026-09-09T06:52:38.692895Z","caller":"traceutil/trace.go:171","msg":"trace[85845067] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-5hr46; range_end:; response_count:1; response_revision:16369; }","duration":"443.793273ms","start":"2026-09-09T06:52:38.249076Z","end":"2026-09-09T06:52:38.692869Z","steps":["trace[85845067] 'agreement among raft nodes before linearized reading' (duration: 443.427205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.692953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:38.249056Z","time spent":"443.884956ms","remote":"127.0.0.1:58002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20660,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5hr46\" "} {"level":"warn","ts":"2026-09-09T06:52:38.695277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.841301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:52:38.695345Z","caller":"traceutil/trace.go:171","msg":"trace[1097241052] transaction","detail":"{read_only:false; response_revision:16371; number_of_response:1; }","duration":"110.582815ms","start":"2026-09-09T06:52:38.584745Z","end":"2026-09-09T06:52:38.695327Z","steps":["trace[1097241052] 'process raft request' (duration: 110.398431ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:38.695354Z","caller":"traceutil/trace.go:171","msg":"trace[574273357] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16371; }","duration":"404.940883ms","start":"2026-09-09T06:52:38.290394Z","end":"2026-09-09T06:52:38.695335Z","steps":["trace[574273357] 'agreement among raft nodes before linearized reading' (duration: 404.81195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.695403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:38.290343Z","time spent":"405.047995ms","remote":"127.0.0.1:58036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-09-09T06:52:38.695461Z","caller":"traceutil/trace.go:171","msg":"trace[2007418999] transaction","detail":"{read_only:false; response_revision:16370; number_of_response:1; }","duration":"140.246555ms","start":"2026-09-09T06:52:38.555159Z","end":"2026-09-09T06:52:38.695406Z","steps":["trace[2007418999] 'process raft request' (duration: 139.865786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.695568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.48471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:52:38.695621Z","caller":"traceutil/trace.go:171","msg":"trace[1207857173] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16371; }","duration":"150.533281ms","start":"2026-09-09T06:52:38.545072Z","end":"2026-09-09T06:52:38.695605Z","steps":["trace[1207857173] 'agreement among raft nodes before linearized reading' (duration: 150.458489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.695659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.853518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:52:38.695683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.083596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5618"} {"level":"info","ts":"2026-09-09T06:52:38.695696Z","caller":"traceutil/trace.go:171","msg":"trace[738522219] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16371; }","duration":"150.90238ms","start":"2026-09-09T06:52:38.544781Z","end":"2026-09-09T06:52:38.695683Z","steps":["trace[738522219] 'agreement among raft nodes before linearized reading' (duration: 150.861039ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:38.695735Z","caller":"traceutil/trace.go:171","msg":"trace[215153748] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16371; }","duration":"135.151847ms","start":"2026-09-09T06:52:38.560552Z","end":"2026-09-09T06:52:38.695704Z","steps":["trace[215153748] 'agreement among raft nodes before linearized reading' (duration: 135.077916ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.695782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.76398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:52:38.695837Z","caller":"traceutil/trace.go:171","msg":"trace[1240864457] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16371; }","duration":"148.844022ms","start":"2026-09-09T06:52:38.546982Z","end":"2026-09-09T06:52:38.695826Z","steps":["trace[1240864457] 'agreement among raft nodes before linearized reading' (duration: 148.76665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.695292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.141922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:52:38.695886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.228096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:52:38.695884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.171589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5618"} {"level":"info","ts":"2026-09-09T06:52:38.695919Z","caller":"traceutil/trace.go:171","msg":"trace[1046966820] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16371; }","duration":"154.326438ms","start":"2026-09-09T06:52:38.541583Z","end":"2026-09-09T06:52:38.69591Z","steps":["trace[1046966820] 'agreement among raft nodes before linearized reading' (duration: 154.247606ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:38.695933Z","caller":"traceutil/trace.go:171","msg":"trace[248644222] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16371; }","duration":"149.24463ms","start":"2026-09-09T06:52:38.54668Z","end":"2026-09-09T06:52:38.695925Z","steps":["trace[248644222] 'agreement among raft nodes before linearized reading' (duration: 149.171589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.695909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.2938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5618"} {"level":"info","ts":"2026-09-09T06:52:38.695945Z","caller":"traceutil/trace.go:171","msg":"trace[1621240451] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16371; }","duration":"150.805578ms","start":"2026-09-09T06:52:38.545093Z","end":"2026-09-09T06:52:38.695898Z","steps":["trace[1621240451] 'agreement among raft nodes before linearized reading' (duration: 150.151233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.695972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.201045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5618"} {"level":"info","ts":"2026-09-09T06:52:38.695988Z","caller":"traceutil/trace.go:171","msg":"trace[2046846153] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16371; }","duration":"135.411153ms","start":"2026-09-09T06:52:38.560552Z","end":"2026-09-09T06:52:38.695963Z","steps":["trace[2046846153] 'agreement among raft nodes before linearized reading' (duration: 135.27349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.696013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.377639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:52:38.696023Z","caller":"traceutil/trace.go:171","msg":"trace[636494912] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16371; }","duration":"137.279787ms","start":"2026-09-09T06:52:38.558731Z","end":"2026-09-09T06:52:38.69601Z","steps":["trace[636494912] 'agreement among raft nodes before linearized reading' (duration: 137.194975ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.69562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.529157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:52:38.696043Z","caller":"traceutil/trace.go:171","msg":"trace[2138842043] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16371; }","duration":"154.444771ms","start":"2026-09-09T06:52:38.541591Z","end":"2026-09-09T06:52:38.696036Z","steps":["trace[2138842043] 'agreement among raft nodes before linearized reading' (duration: 154.39961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.695763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.175491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"warn","ts":"2026-09-09T06:52:38.696079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.193781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-09-09T06:52:38.696084Z","caller":"traceutil/trace.go:171","msg":"trace[1523843434] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16371; }","duration":"153.007757ms","start":"2026-09-09T06:52:38.543051Z","end":"2026-09-09T06:52:38.696059Z","steps":["trace[1523843434] 'agreement among raft nodes before linearized reading' (duration: 152.532777ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:38.696104Z","caller":"traceutil/trace.go:171","msg":"trace[1610378330] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16371; }","duration":"153.537929ms","start":"2026-09-09T06:52:38.542555Z","end":"2026-09-09T06:52:38.696093Z","steps":["trace[1610378330] 'agreement among raft nodes before linearized reading' (duration: 153.020687ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:38.696127Z","caller":"traceutil/trace.go:171","msg":"trace[1595347743] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16371; }","duration":"153.249342ms","start":"2026-09-09T06:52:38.54286Z","end":"2026-09-09T06:52:38.696109Z","steps":["trace[1595347743] 'agreement among raft nodes before linearized reading' (duration: 153.1497ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:41.601698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.91231ms","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:6"} {"level":"info","ts":"2026-09-09T06:52:41.601777Z","caller":"traceutil/trace.go:171","msg":"trace[95854581] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:16402; }","duration":"387.047784ms","start":"2026-09-09T06:52:41.214711Z","end":"2026-09-09T06:52:41.601759Z","steps":["trace[95854581] 'count revisions from in-memory index tree' (duration: 386.791578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:41.601849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:41.214691Z","time spent":"387.143245ms","remote":"127.0.0.1:52858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:52:41.602109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.529174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:683"} {"level":"info","ts":"2026-09-09T06:52:41.602178Z","caller":"traceutil/trace.go:171","msg":"trace[2123246155] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16402; }","duration":"305.637267ms","start":"2026-09-09T06:52:41.296523Z","end":"2026-09-09T06:52:41.60216Z","steps":["trace[2123246155] 'range keys from in-memory index tree' (duration: 305.36076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:41.60221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.185486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T06:52:41.602189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.454507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-78vsk\" ","response":"range_response_count:1 size:6525"} {"level":"warn","ts":"2026-09-09T06:52:41.602238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.982428ms","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-09-09T06:52:41.602277Z","caller":"traceutil/trace.go:171","msg":"trace[375133103] range","detail":"{range_begin:/registry/pods/openstack/keepalived-78vsk; range_end:; response_count:1; response_revision:16402; }","duration":"269.585921ms","start":"2026-09-09T06:52:41.33267Z","end":"2026-09-09T06:52:41.602255Z","steps":["trace[375133103] 'range keys from in-memory index tree' (duration: 269.297734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:41.602286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.266492ms","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":"warn","ts":"2026-09-09T06:52:41.602307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.151383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:1 size:4913"} {"level":"info","ts":"2026-09-09T06:52:41.602325Z","caller":"traceutil/trace.go:171","msg":"trace[1197445553] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:16402; }","duration":"313.342913ms","start":"2026-09-09T06:52:41.288971Z","end":"2026-09-09T06:52:41.602314Z","steps":["trace[1197445553] 'count revisions from in-memory index tree' (duration: 313.17558ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:41.602343Z","caller":"traceutil/trace.go:171","msg":"trace[347185175] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:1; response_revision:16402; }","duration":"311.197833ms","start":"2026-09-09T06:52:41.291138Z","end":"2026-09-09T06:52:41.602336Z","steps":["trace[347185175] 'range keys from in-memory index tree' (duration: 311.095892ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:41.602354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:41.288949Z","time spent":"313.395125ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:52:41.602352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.273489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-v2vrl\" ","response":"range_response_count:1 size:9792"} {"level":"warn","ts":"2026-09-09T06:52:41.60236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:41.291133Z","time spent":"311.223035ms","remote":"127.0.0.1:58036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4936,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-09-09T06:52:41.602389Z","caller":"traceutil/trace.go:171","msg":"trace[161721586] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-v2vrl; range_end:; response_count:1; response_revision:16402; }","duration":"240.34774ms","start":"2026-09-09T06:52:41.36203Z","end":"2026-09-09T06:52:41.602378Z","steps":["trace[161721586] 'range keys from in-memory index tree' (duration: 240.150656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:41.602438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.293624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-09T06:52:41.602248Z","caller":"traceutil/trace.go:171","msg":"trace[21566386] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16402; }","duration":"312.224627ms","start":"2026-09-09T06:52:41.290012Z","end":"2026-09-09T06:52:41.602237Z","steps":["trace[21566386] 'range keys from in-memory index tree' (duration: 312.125495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:41.602517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:41.289964Z","time spent":"312.542404ms","remote":"127.0.0.1:57990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":29,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-09-09T06:52:41.602514Z","caller":"traceutil/trace.go:171","msg":"trace[298137264] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16402; }","duration":"310.376486ms","start":"2026-09-09T06:52:41.292124Z","end":"2026-09-09T06:52:41.6025Z","steps":["trace[298137264] 'range keys from in-memory index tree' (duration: 310.222793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:41.602594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:41.292117Z","time spent":"310.464048ms","remote":"127.0.0.1:57990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-09-09T06:52:41.602283Z","caller":"traceutil/trace.go:171","msg":"trace[1191503151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16402; }","duration":"256.05748ms","start":"2026-09-09T06:52:41.346215Z","end":"2026-09-09T06:52:41.602272Z","steps":["trace[1191503151] 'range keys from in-memory index tree' (duration: 255.850855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:41.602709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.63172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-09-09T06:52:41.603154Z","caller":"traceutil/trace.go:171","msg":"trace[716380455] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16402; }","duration":"167.097461ms","start":"2026-09-09T06:52:41.436047Z","end":"2026-09-09T06:52:41.603145Z","steps":["trace[716380455] 'range keys from in-memory index tree' (duration: 166.527947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:41.602223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:52:41.296503Z","time spent":"305.713178ms","remote":"127.0.0.1:57990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":706,"request content":"key:\"/registry/services/endpoints/openstack/nova-metadata\" "} {"level":"warn","ts":"2026-09-09T06:52:41.602712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.680142ms","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-09-09T06:52:41.603291Z","caller":"traceutil/trace.go:171","msg":"trace[964061712] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:16402; }","duration":"222.279146ms","start":"2026-09-09T06:52:41.380999Z","end":"2026-09-09T06:52:41.603279Z","steps":["trace[964061712] 'count revisions from in-memory index tree' (duration: 221.56427ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:41.603026Z","caller":"traceutil/trace.go:171","msg":"trace[1947889898] transaction","detail":"{read_only:false; response_revision:16403; number_of_response:1; }","duration":"161.710016ms","start":"2026-09-09T06:52:41.4413Z","end":"2026-09-09T06:52:41.60301Z","steps":["trace[1947889898] 'process raft request' (duration: 117.124065ms)","trace[1947889898] 'compare' (duration: 44.446028ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:52:42.425445Z","caller":"traceutil/trace.go:171","msg":"trace[1951323057] transaction","detail":"{read_only:false; response_revision:16409; number_of_response:1; }","duration":"138.436835ms","start":"2026-09-09T06:52:42.286982Z","end":"2026-09-09T06:52:42.425419Z","steps":["trace[1951323057] 'process raft request' (duration: 96.707748ms)","trace[1951323057] 'compare' (duration: 41.624135ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:52:50.552596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.439403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pxxb2\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-09-09T06:52:50.552751Z","caller":"traceutil/trace.go:171","msg":"trace[1749673022] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pxxb2; range_end:; response_count:1; response_revision:16489; }","duration":"138.615537ms","start":"2026-09-09T06:52:50.414114Z","end":"2026-09-09T06:52:50.55273Z","steps":["trace[1749673022] 'range keys from in-memory index tree' (duration: 138.205328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:50.553021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.798791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pxxb2\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-09-09T06:52:50.55311Z","caller":"traceutil/trace.go:171","msg":"trace[147375667] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pxxb2; range_end:; response_count:1; response_revision:16489; }","duration":"141.938574ms","start":"2026-09-09T06:52:50.411149Z","end":"2026-09-09T06:52:50.553088Z","steps":["trace[147375667] 'range keys from in-memory index tree' (duration: 141.33849ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:15.53886Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14576} {"level":"info","ts":"2026-09-09T06:54:15.629112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14576,"took":"87.100986ms","hash":1326414510,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":31715328,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T06:54:15.62918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1326414510,"revision":14576,"compact-revision":12590} {"level":"info","ts":"2026-09-09T06:57:33.433693Z","caller":"traceutil/trace.go:171","msg":"trace[1605671055] transaction","detail":"{read_only:false; response_revision:18564; number_of_response:1; }","duration":"110.958612ms","start":"2026-09-09T06:57:33.322717Z","end":"2026-09-09T06:57:33.433676Z","steps":["trace[1605671055] 'process raft request' (duration: 110.815079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:57:36.201631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.745441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T06:57:36.201919Z","caller":"traceutil/trace.go:171","msg":"trace[84021774] transaction","detail":"{read_only:false; response_revision:18582; number_of_response:1; }","duration":"184.570593ms","start":"2026-09-09T06:57:36.017312Z","end":"2026-09-09T06:57:36.201882Z","steps":["trace[84021774] 'process raft request' (duration: 18.378372ms)","trace[84021774] 'compare' (duration: 165.512346ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:57:36.20208Z","caller":"traceutil/trace.go:171","msg":"trace[605940126] linearizableReadLoop","detail":"{readStateIndex:19221; appliedIndex:19220; }","duration":"166.097179ms","start":"2026-09-09T06:57:36.035739Z","end":"2026-09-09T06:57:36.201836Z","steps":["trace[605940126] 'read index received' (duration: 84.262µs)","trace[605940126] 'applied index is now lower than readState.Index' (duration: 166.011177ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:57:36.202384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.644732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:57:36.20245Z","caller":"traceutil/trace.go:171","msg":"trace[860916217] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:18582; }","duration":"166.717704ms","start":"2026-09-09T06:57:36.035711Z","end":"2026-09-09T06:57:36.202429Z","steps":["trace[860916217] 'agreement among raft nodes before linearized reading' (duration: 166.460228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:57:40.292397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.147445ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:57:40.292614Z","caller":"traceutil/trace.go:171","msg":"trace[348327527] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:18611; }","duration":"164.39101ms","start":"2026-09-09T06:57:40.128187Z","end":"2026-09-09T06:57:40.292578Z","steps":["trace[348327527] 'range keys from in-memory index tree' (duration: 164.122534ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:57:40.406297Z","caller":"traceutil/trace.go:171","msg":"trace[117609465] transaction","detail":"{read_only:false; response_revision:18612; number_of_response:1; }","duration":"192.394064ms","start":"2026-09-09T06:57:40.213853Z","end":"2026-09-09T06:57:40.406247Z","steps":["trace[117609465] 'process raft request' (duration: 192.041946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:57:40.802322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.05602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T06:57:40.802467Z","caller":"traceutil/trace.go:171","msg":"trace[2089699008] linearizableReadLoop","detail":"{readStateIndex:19253; appliedIndex:19252; }","duration":"289.685702ms","start":"2026-09-09T06:57:40.512758Z","end":"2026-09-09T06:57:40.802444Z","steps":["trace[2089699008] 'read index received' (duration: 123.193603ms)","trace[2089699008] 'applied index is now lower than readState.Index' (duration: 166.490479ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:57:40.802481Z","caller":"traceutil/trace.go:171","msg":"trace[1483018959] transaction","detail":"{read_only:false; response_revision:18613; number_of_response:1; }","duration":"298.255629ms","start":"2026-09-09T06:57:40.5042Z","end":"2026-09-09T06:57:40.802455Z","steps":["trace[1483018959] 'process raft request' (duration: 131.952264ms)","trace[1483018959] 'compare' (duration: 165.857115ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:57:40.802863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.092521ms","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":"warn","ts":"2026-09-09T06:57:40.802917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.104314ms","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-09-09T06:57:40.803058Z","caller":"traceutil/trace.go:171","msg":"trace[634810229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18613; }","duration":"174.305669ms","start":"2026-09-09T06:57:40.628723Z","end":"2026-09-09T06:57:40.803029Z","steps":["trace[634810229] 'agreement among raft nodes before linearized reading' (duration: 173.92731ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:57:40.803137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.175707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/network\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:57:40.802927Z","caller":"traceutil/trace.go:171","msg":"trace[909442430] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:18613; }","duration":"290.198124ms","start":"2026-09-09T06:57:40.512711Z","end":"2026-09-09T06:57:40.802909Z","steps":["trace[909442430] 'agreement among raft nodes before linearized reading' (duration: 289.848266ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:57:40.803219Z","caller":"traceutil/trace.go:171","msg":"trace[83202476] range","detail":"{range_begin:/registry/ingress/openstack/network; range_end:; response_count:0; response_revision:18613; }","duration":"168.424034ms","start":"2026-09-09T06:57:40.634776Z","end":"2026-09-09T06:57:40.8032Z","steps":["trace[83202476] 'agreement among raft nodes before linearized reading' (duration: 168.300941ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:57:46.202126Z","caller":"traceutil/trace.go:171","msg":"trace[1016086345] transaction","detail":"{read_only:false; response_revision:18705; number_of_response:1; }","duration":"147.102073ms","start":"2026-09-09T06:57:46.054997Z","end":"2026-09-09T06:57:46.202099Z","steps":["trace[1016086345] 'process raft request' (duration: 146.937899ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:57:46.203282Z","caller":"traceutil/trace.go:171","msg":"trace[1460238367] transaction","detail":"{read_only:false; response_revision:18706; number_of_response:1; }","duration":"130.768418ms","start":"2026-09-09T06:57:46.07249Z","end":"2026-09-09T06:57:46.203258Z","steps":["trace[1460238367] 'process raft request' (duration: 130.596874ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:58:43.904987Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9bd37ed2859641e3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T06:58:43.926205Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-09T06:58:43.926292Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-09T06:59:15.545981Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17077} {"level":"info","ts":"2026-09-09T06:59:15.651569Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17077,"took":"102.364854ms","hash":2166304990,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":33959936,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-09T06:59:15.651669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2166304990,"revision":17077,"compact-revision":14576} {"level":"info","ts":"2026-09-09T07:02:06.420459Z","caller":"traceutil/trace.go:171","msg":"trace[2107632281] transaction","detail":"{read_only:false; response_revision:20827; number_of_response:1; }","duration":"152.535966ms","start":"2026-09-09T07:02:06.267895Z","end":"2026-09-09T07:02:06.420431Z","steps":["trace[2107632281] 'process raft request' (duration: 91.941633ms)","trace[2107632281] 'compare' (duration: 60.353168ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:04:15.554089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19633} {"level":"info","ts":"2026-09-09T07:04:15.682939Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19633,"took":"123.775222ms","hash":3877529772,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":30093312,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T07:04:15.682999Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3877529772,"revision":19633,"compact-revision":17077} {"level":"warn","ts":"2026-09-09T07:04:19.233289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.537069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-09T07:04:19.233364Z","caller":"traceutil/trace.go:171","msg":"trace[650436118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21709; }","duration":"108.644932ms","start":"2026-09-09T07:04:19.124701Z","end":"2026-09-09T07:04:19.233346Z","steps":["trace[650436118] 'range keys from in-memory index tree' (duration: 108.364475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:04:19.233377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.428027ms","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-09-09T07:04:19.23342Z","caller":"traceutil/trace.go:171","msg":"trace[271250002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21709; }","duration":"118.493198ms","start":"2026-09-09T07:04:19.114914Z","end":"2026-09-09T07:04:19.233407Z","steps":["trace[271250002] 'range keys from in-memory index tree' (duration: 118.256144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:04:19.233363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.519409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T07:04:19.233577Z","caller":"traceutil/trace.go:171","msg":"trace[1872081361] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:21709; }","duration":"124.760925ms","start":"2026-09-09T07:04:19.10876Z","end":"2026-09-09T07:04:19.233521Z","steps":["trace[1872081361] 'count revisions from in-memory index tree' (duration: 124.442256ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:19.352768Z","caller":"traceutil/trace.go:171","msg":"trace[990395289] transaction","detail":"{read_only:false; response_revision:21710; number_of_response:1; }","duration":"115.06294ms","start":"2026-09-09T07:04:19.237686Z","end":"2026-09-09T07:04:19.352749Z","steps":["trace[990395289] 'process raft request' (duration: 114.918987ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:19.573144Z","caller":"traceutil/trace.go:171","msg":"trace[953120951] linearizableReadLoop","detail":"{readStateIndex:22455; appliedIndex:22453; }","duration":"240.237273ms","start":"2026-09-09T07:04:19.332879Z","end":"2026-09-09T07:04:19.573117Z","steps":["trace[953120951] 'read index received' (duration: 19.823746ms)","trace[953120951] 'applied index is now lower than readState.Index' (duration: 220.412287ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:04:19.573269Z","caller":"traceutil/trace.go:171","msg":"trace[156707191] transaction","detail":"{read_only:false; response_revision:21711; number_of_response:1; }","duration":"335.474007ms","start":"2026-09-09T07:04:19.237782Z","end":"2026-09-09T07:04:19.573256Z","steps":["trace[156707191] 'process raft request' (duration: 235.576456ms)","trace[156707191] 'compare' (duration: 99.649495ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:04:19.573359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:04:19.237757Z","time spent":"335.536078ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T07:04:19.573602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.071124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T07:04:19.57367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.784366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T07:04:19.573678Z","caller":"traceutil/trace.go:171","msg":"trace[453809717] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:21711; }","duration":"136.175608ms","start":"2026-09-09T07:04:19.437486Z","end":"2026-09-09T07:04:19.573662Z","steps":["trace[453809717] 'agreement among raft nodes before linearized reading' (duration: 136.057974ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:19.573705Z","caller":"traceutil/trace.go:171","msg":"trace[647096867] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:21711; }","duration":"240.846708ms","start":"2026-09-09T07:04:19.332846Z","end":"2026-09-09T07:04:19.573693Z","steps":["trace[647096867] 'agreement among raft nodes before linearized reading' (duration: 240.789395ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:35.536196Z","caller":"traceutil/trace.go:171","msg":"trace[334866935] transaction","detail":"{read_only:false; response_revision:21792; number_of_response:1; }","duration":"103.957475ms","start":"2026-09-09T07:04:35.432191Z","end":"2026-09-09T07:04:35.536148Z","steps":["trace[334866935] 'process raft request' (duration: 103.638627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:04:35.771122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.17696ms","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-09-09T07:04:35.771222Z","caller":"traceutil/trace.go:171","msg":"trace[2005607446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21792; }","duration":"114.327124ms","start":"2026-09-09T07:04:35.656877Z","end":"2026-09-09T07:04:35.771204Z","steps":["trace[2005607446] 'range keys from in-memory index tree' (duration: 113.975966ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:35.947593Z","caller":"traceutil/trace.go:171","msg":"trace[482326170] transaction","detail":"{read_only:false; response_revision:21793; number_of_response:1; }","duration":"172.056013ms","start":"2026-09-09T07:04:35.775514Z","end":"2026-09-09T07:04:35.94757Z","steps":["trace[482326170] 'process raft request' (duration: 171.89723ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:36.17971Z","caller":"traceutil/trace.go:171","msg":"trace[184313934] transaction","detail":"{read_only:false; response_revision:21794; number_of_response:1; }","duration":"403.255969ms","start":"2026-09-09T07:04:35.77643Z","end":"2026-09-09T07:04:36.179686Z","steps":["trace[184313934] 'process raft request' (duration: 403.070575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:04:36.179889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:04:35.776411Z","time spent":"403.372871ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T07:04:36.17996Z","caller":"traceutil/trace.go:171","msg":"trace[15508549] transaction","detail":"{read_only:false; response_revision:21795; number_of_response:1; }","duration":"403.334161ms","start":"2026-09-09T07:04:35.776609Z","end":"2026-09-09T07:04:36.179943Z","steps":["trace[15508549] 'process raft request' (duration: 403.031564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:04:36.180033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:04:35.776592Z","time spent":"403.403612ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T07:04:36.180194Z","caller":"traceutil/trace.go:171","msg":"trace[1635146693] transaction","detail":"{read_only:false; response_revision:21797; number_of_response:1; }","duration":"388.007657ms","start":"2026-09-09T07:04:35.792172Z","end":"2026-09-09T07:04:36.18018Z","steps":["trace[1635146693] 'process raft request' (duration: 387.928255ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:36.180195Z","caller":"traceutil/trace.go:171","msg":"trace[2054713747] transaction","detail":"{read_only:false; response_revision:21796; number_of_response:1; }","duration":"388.055858ms","start":"2026-09-09T07:04:35.792114Z","end":"2026-09-09T07:04:36.18017Z","steps":["trace[2054713747] 'process raft request' (duration: 387.770052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:04:36.180324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:04:35.792091Z","time spent":"388.159081ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T07:04:36.180352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:04:35.792157Z","time spent":"388.085829ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T07:04:36.738549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.425069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T07:04:36.738646Z","caller":"traceutil/trace.go:171","msg":"trace[1262851955] transaction","detail":"{read_only:false; response_revision:21798; number_of_response:1; }","duration":"714.032597ms","start":"2026-09-09T07:04:36.024595Z","end":"2026-09-09T07:04:36.738627Z","steps":["trace[1262851955] 'process raft request' (duration: 252.451295ms)","trace[1262851955] 'compare' (duration: 461.309376ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:04:36.738703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:04:36.024562Z","time spent":"714.111909ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T07:04:36.743042Z","caller":"traceutil/trace.go:171","msg":"trace[1780502497] transaction","detail":"{read_only:false; response_revision:21799; number_of_response:1; }","duration":"308.68976ms","start":"2026-09-09T07:04:36.434321Z","end":"2026-09-09T07:04:36.743011Z","steps":["trace[1780502497] 'process raft request' (duration: 308.496136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:04:36.743351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:04:36.434296Z","time spent":"308.826333ms","remote":"127.0.0.1:58062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T07:04:37.098468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.292206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T07:04:37.09857Z","caller":"traceutil/trace.go:171","msg":"trace[1491105765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21799; }","duration":"214.44275ms","start":"2026-09-09T07:04:36.884102Z","end":"2026-09-09T07:04:37.098545Z","steps":["trace[1491105765] 'range keys from in-memory index tree' (duration: 214.196534ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:08:46.503154Z","caller":"traceutil/trace.go:171","msg":"trace[710436081] transaction","detail":"{read_only:false; response_revision:24379; number_of_response:1; }","duration":"138.116721ms","start":"2026-09-09T07:08:46.364995Z","end":"2026-09-09T07:08:46.503125Z","steps":["trace[710436081] 'process raft request' (duration: 137.969217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:08:46.931118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.103764ms","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-09-09T07:08:46.931202Z","caller":"traceutil/trace.go:171","msg":"trace[295747367] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:24380; }","duration":"271.226088ms","start":"2026-09-09T07:08:46.659959Z","end":"2026-09-09T07:08:46.931185Z","steps":["trace[295747367] 'count revisions from in-memory index tree' (duration: 271.058653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:08:46.931129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.894157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-09T07:08:46.931308Z","caller":"traceutil/trace.go:171","msg":"trace[571122133] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:24380; }","duration":"178.112192ms","start":"2026-09-09T07:08:46.753178Z","end":"2026-09-09T07:08:46.93129Z","steps":["trace[571122133] 'count revisions from in-memory index tree' (duration: 177.817526ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:08:46.931959Z","caller":"traceutil/trace.go:171","msg":"trace[935527667] transaction","detail":"{read_only:false; response_revision:24381; number_of_response:1; }","duration":"155.622214ms","start":"2026-09-09T07:08:46.776303Z","end":"2026-09-09T07:08:46.931925Z","steps":["trace[935527667] 'process raft request' (duration: 151.96719ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:09:15.560781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21692} {"level":"info","ts":"2026-09-09T07:09:15.673142Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21692,"took":"108.919809ms","hash":3783718675,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":42512384,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-09T07:09:15.673211Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3783718675,"revision":21692,"compact-revision":19633} {"level":"info","ts":"2026-09-09T07:10:18.686379Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006662.wal"} {"level":"info","ts":"2026-09-09T07:14:15.567466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24655} {"level":"info","ts":"2026-09-09T07:14:15.720541Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24655,"took":"149.253533ms","hash":574388384,"current-db-size-bytes":57290752,"current-db-size":"57 MB","current-db-size-in-use-bytes":33173504,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-09T07:14:15.720596Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":574388384,"revision":24655,"compact-revision":21692} {"level":"info","ts":"2026-09-09T07:18:36.677437Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9bd37ed2859641e3","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T07:18:36.685587Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-09T07:18:36.685893Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}