{"level":"warn","ts":"2026-07-16T15:54:04.125409Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T15:54:04.125544Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-07-16T15:54:04.125634Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T15:54:04.125649Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-07-16T15:54:04.125669Z","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-07-16T15:54:04.12636Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-07-16T15:54:04.126489Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-07-16T15:54:04.130402Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.602919ms"} {"level":"info","ts":"2026-07-16T15:54:04.13658Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-07-16T15:54:04.136651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T15:54:04.136672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-07-16T15:54:04.13668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T15:54:04.136695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-07-16T15:54:04.136728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-07-16T15:54:04.143028Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T15:54:04.145038Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T15:54:04.146398Z","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-07-16T15:54:04.148692Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T15:54:04.148923Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T15:54:04.149071Z","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-07-16T15:54:04.149316Z","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-07-16T15:54:04.149342Z","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-07-16T15:54:04.15075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-07-16T15:54:04.150986Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-07-16T15:54:04.155529Z","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-07-16T15:54:04.155753Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-07-16T15:54:04.155838Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-07-16T15:54:04.155892Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T15:54:04.155969Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T15:54:05.136993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T15:54:05.137107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T15:54:05.137138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-07-16T15:54:05.137161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-07-16T15:54:05.13718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-07-16T15:54:05.137196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-07-16T15:54:05.13721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-07-16T15:54:05.138819Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T15:54:05.138932Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T15:54:05.13893Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T15:54:05.138866Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T15:54:05.139207Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T15:54:05.13926Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T15:54:05.14002Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T15:54:05.14013Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T15:54:05.140183Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T15:54:05.142296Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-07-16T15:54:05.143324Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-16T15:54:18.480721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.68975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T15:54:18.48088Z","caller":"traceutil/trace.go:171","msg":"trace[201693690] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"103.961398ms","start":"2026-07-16T15:54:18.3769Z","end":"2026-07-16T15:54:18.480861Z","steps":["trace[201693690] 'read index received' (duration: 47.761µs)","trace[201693690] 'applied index is now lower than readState.Index' (duration: 103.911917ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T15:54:18.480977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.07377ms","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-07-16T15:54:18.48095Z","caller":"traceutil/trace.go:171","msg":"trace[1041138572] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"163.907257ms","start":"2026-07-16T15:54:18.316978Z","end":"2026-07-16T15:54:18.480885Z","steps":["trace[1041138572] 'process raft request' (duration: 55.386141ms)","trace[1041138572] 'compare' (duration: 107.501146ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:54:18.481016Z","caller":"traceutil/trace.go:171","msg":"trace[2075928760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"104.168602ms","start":"2026-07-16T15:54:18.376837Z","end":"2026-07-16T15:54:18.481006Z","steps":["trace[2075928760] 'agreement among raft nodes before linearized reading' (duration: 104.08818ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:54:49.470159Z","caller":"traceutil/trace.go:171","msg":"trace[1239705282] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"165.60153ms","start":"2026-07-16T15:54:49.304516Z","end":"2026-07-16T15:54:49.470117Z","steps":["trace[1239705282] 'read index received' (duration: 165.437736ms)","trace[1239705282] 'applied index is now lower than readState.Index' (duration: 162.924µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:54:49.470278Z","caller":"traceutil/trace.go:171","msg":"trace[1990183017] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"231.078056ms","start":"2026-07-16T15:54:49.239171Z","end":"2026-07-16T15:54:49.470249Z","steps":["trace[1990183017] 'process raft request' (duration: 230.703138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:54:49.47042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.870116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:54:49.470536Z","caller":"traceutil/trace.go:171","msg":"trace[2054264573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"166.030428ms","start":"2026-07-16T15:54:49.304475Z","end":"2026-07-16T15:54:49.470506Z","steps":["trace[2054264573] 'agreement among raft nodes before linearized reading' (duration: 165.818304ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:54:49.537202Z","caller":"traceutil/trace.go:171","msg":"trace[140491991] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"231.910242ms","start":"2026-07-16T15:54:49.305257Z","end":"2026-07-16T15:54:49.537167Z","steps":["trace[140491991] 'process raft request' (duration: 231.453313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:54:49.537421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.917656ms","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-07-16T15:54:49.537575Z","caller":"traceutil/trace.go:171","msg":"trace[430140776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"226.10768ms","start":"2026-07-16T15:54:49.311405Z","end":"2026-07-16T15:54:49.537513Z","steps":["trace[430140776] 'agreement among raft nodes before linearized reading' (duration: 225.790683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:54:49.962785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.558504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T15:54:49.962982Z","caller":"traceutil/trace.go:171","msg":"trace[96781109] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"351.549147ms","start":"2026-07-16T15:54:49.611405Z","end":"2026-07-16T15:54:49.962954Z","steps":["trace[96781109] 'read index received' (duration: 195.630706ms)","trace[96781109] 'applied index is now lower than readState.Index' (duration: 155.916231ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:54:49.963015Z","caller":"traceutil/trace.go:171","msg":"trace[177350096] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"419.91015ms","start":"2026-07-16T15:54:49.543056Z","end":"2026-07-16T15:54:49.962966Z","steps":["trace[177350096] 'process raft request' (duration: 264.017649ms)","trace[177350096] 'compare' (duration: 155.354799ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T15:54:49.963177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.103575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-16T15:54:49.963187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.750621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:54:49.963246Z","caller":"traceutil/trace.go:171","msg":"trace[1752836601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"213.172846ms","start":"2026-07-16T15:54:49.750057Z","end":"2026-07-16T15:54:49.96323Z","steps":["trace[1752836601] 'agreement among raft nodes before linearized reading' (duration: 213.050984ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:54:49.963292Z","caller":"traceutil/trace.go:171","msg":"trace[1160769331] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:630; }","duration":"351.925924ms","start":"2026-07-16T15:54:49.611337Z","end":"2026-07-16T15:54:49.963263Z","steps":["trace[1160769331] 'agreement among raft nodes before linearized reading' (duration: 351.736161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:54:49.963365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:54:49.611306Z","time spent":"352.046996ms","remote":"127.0.0.1:45954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-16T15:54:49.96331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:54:49.543031Z","time spent":"420.123583ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T15:56:23.072811Z","caller":"traceutil/trace.go:171","msg":"trace[215776636] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"274.442521ms","start":"2026-07-16T15:56:22.798336Z","end":"2026-07-16T15:56:23.072778Z","steps":["trace[215776636] 'process raft request' (duration: 274.231837ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:56:23.194565Z","caller":"traceutil/trace.go:171","msg":"trace[2062077544] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"108.071211ms","start":"2026-07-16T15:56:23.086461Z","end":"2026-07-16T15:56:23.194532Z","steps":["trace[2062077544] 'process raft request' (duration: 76.592157ms)","trace[2062077544] 'compare' (duration: 31.145188ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:56:23.495405Z","caller":"traceutil/trace.go:171","msg":"trace[133993490] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"293.904562ms","start":"2026-07-16T15:56:23.201451Z","end":"2026-07-16T15:56:23.495356Z","steps":["trace[133993490] 'process raft request' (duration: 196.773735ms)","trace[133993490] 'compare' (duration: 96.889752ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:56:23.506496Z","caller":"traceutil/trace.go:171","msg":"trace[1948451752] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"251.542955ms","start":"2026-07-16T15:56:23.254906Z","end":"2026-07-16T15:56:23.506449Z","steps":["trace[1948451752] 'process raft request' (duration: 251.157288ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:56:23.515213Z","caller":"traceutil/trace.go:171","msg":"trace[778693346] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"118.717219ms","start":"2026-07-16T15:56:23.396415Z","end":"2026-07-16T15:56:23.515132Z","steps":["trace[778693346] 'process raft request' (duration: 118.452674ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:56:23.51523Z","caller":"traceutil/trace.go:171","msg":"trace[1683824380] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1959; }","duration":"127.027682ms","start":"2026-07-16T15:56:23.388149Z","end":"2026-07-16T15:56:23.515176Z","steps":["trace[1683824380] 'read index received' (duration: 10.087658ms)","trace[1683824380] 'applied index is now lower than readState.Index' (duration: 116.935954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T15:56:23.515439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.267286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-16T15:56:23.515511Z","caller":"traceutil/trace.go:171","msg":"trace[715987286] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1894; }","duration":"127.407069ms","start":"2026-07-16T15:56:23.388084Z","end":"2026-07-16T15:56:23.515491Z","steps":["trace[715987286] 'agreement among raft nodes before linearized reading' (duration: 127.192575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:56:30.048248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.0423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T15:56:30.048419Z","caller":"traceutil/trace.go:171","msg":"trace[542058818] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"122.512163ms","start":"2026-07-16T15:56:29.925883Z","end":"2026-07-16T15:56:30.048395Z","steps":["trace[542058818] 'process raft request' (duration: 13.217418ms)","trace[542058818] 'compare' (duration: 108.924298ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T15:56:35.936636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.836236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-16T15:56:35.936695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.565797ms","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-07-16T15:56:35.936751Z","caller":"traceutil/trace.go:171","msg":"trace[1849049722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1991; }","duration":"197.014929ms","start":"2026-07-16T15:56:35.739695Z","end":"2026-07-16T15:56:35.93671Z","steps":["trace[1849049722] 'range keys from in-memory index tree' (duration: 196.55918ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:56:35.936798Z","caller":"traceutil/trace.go:171","msg":"trace[1652995145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1991; }","duration":"202.738731ms","start":"2026-07-16T15:56:35.734036Z","end":"2026-07-16T15:56:35.936775Z","steps":["trace[1652995145] 'range keys from in-memory index tree' (duration: 202.298603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:56:35.936866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.927887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T15:56:35.936982Z","caller":"traceutil/trace.go:171","msg":"trace[179031213] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1991; }","duration":"112.12669ms","start":"2026-07-16T15:56:35.824831Z","end":"2026-07-16T15:56:35.936958Z","steps":["trace[179031213] 'count revisions from in-memory index tree' (duration: 111.766984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:57:14.66416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.244718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:57:14.664308Z","caller":"traceutil/trace.go:171","msg":"trace[1213739764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2217; }","duration":"359.469392ms","start":"2026-07-16T15:57:14.304809Z","end":"2026-07-16T15:57:14.664278Z","steps":["trace[1213739764] 'range keys from in-memory index tree' (duration: 359.069484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:57:14.664364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:57:14.304787Z","time spent":"359.563993ms","remote":"127.0.0.1:45758","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-07-16T15:57:14.664363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.864023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T15:57:14.664834Z","caller":"traceutil/trace.go:171","msg":"trace[1084109279] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2298; }","duration":"273.751756ms","start":"2026-07-16T15:57:14.391055Z","end":"2026-07-16T15:57:14.664807Z","steps":["trace[1084109279] 'read index received' (duration: 118.267531ms)","trace[1084109279] 'applied index is now lower than readState.Index' (duration: 155.481415ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:57:14.664853Z","caller":"traceutil/trace.go:171","msg":"trace[1754262103] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"320.046904ms","start":"2026-07-16T15:57:14.344703Z","end":"2026-07-16T15:57:14.66475Z","steps":["trace[1754262103] 'process raft request' (duration: 164.675681ms)","trace[1754262103] 'compare' (duration: 154.618369ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T15:57:14.664957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.900888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:57:14.665018Z","caller":"traceutil/trace.go:171","msg":"trace[1807867483] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2218; }","duration":"273.953109ms","start":"2026-07-16T15:57:14.391036Z","end":"2026-07-16T15:57:14.664989Z","steps":["trace[1807867483] 'agreement among raft nodes before linearized reading' (duration: 273.857967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:57:14.665166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:57:14.344677Z","time spent":"320.30394ms","remote":"127.0.0.1:46040","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-07-16T16:03:43.229306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.36581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-q2jgc-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-07-16T16:03:43.229422Z","caller":"traceutil/trace.go:171","msg":"trace[1255262737] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-q2jgc-tls; range_end:; response_count:1; response_revision:5402; }","duration":"125.546484ms","start":"2026-07-16T16:03:43.10385Z","end":"2026-07-16T16:03:43.229397Z","steps":["trace[1255262737] 'range keys from in-memory index tree' (duration: 125.156157ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:03:43.446952Z","caller":"traceutil/trace.go:171","msg":"trace[1837287573] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"200.869477ms","start":"2026-07-16T16:03:43.246036Z","end":"2026-07-16T16:03:43.446905Z","steps":["trace[1837287573] 'process raft request' (duration: 140.90126ms)","trace[1837287573] 'compare' (duration: 59.679492ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:03:43.447201Z","caller":"traceutil/trace.go:171","msg":"trace[1557236012] linearizableReadLoop","detail":"{readStateIndex:5605; appliedIndex:5604; }","duration":"121.944664ms","start":"2026-07-16T16:03:43.325201Z","end":"2026-07-16T16:03:43.447146Z","steps":["trace[1557236012] 'read index received' (duration: 61.709611ms)","trace[1557236012] 'applied index is now lower than readState.Index' (duration: 60.228983ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:03:43.457389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.80872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-16T16:03:43.457512Z","caller":"traceutil/trace.go:171","msg":"trace[569272797] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5404; }","duration":"109.974163ms","start":"2026-07-16T16:03:43.34751Z","end":"2026-07-16T16:03:43.457484Z","steps":["trace[569272797] 'agreement among raft nodes before linearized reading' (duration: 109.674526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:03:43.457557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.269603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b0bee145-4382-46e6-b1ab-bbf6114b7754\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-16T16:03:43.457683Z","caller":"traceutil/trace.go:171","msg":"trace[785853648] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b0bee145-4382-46e6-b1ab-bbf6114b7754; range_end:; response_count:1; response_revision:5404; }","duration":"132.506988ms","start":"2026-07-16T16:03:43.325142Z","end":"2026-07-16T16:03:43.457649Z","steps":["trace[785853648] 'agreement among raft nodes before linearized reading' (duration: 122.230529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:03:43.810494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.023992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T16:03:43.810733Z","caller":"traceutil/trace.go:171","msg":"trace[1404291581] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"163.847993ms","start":"2026-07-16T16:03:43.646826Z","end":"2026-07-16T16:03:43.810674Z","steps":["trace[1404291581] 'process raft request' (duration: 35.555086ms)","trace[1404291581] 'compare' (duration: 127.502841ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:03:56.701664Z","caller":"traceutil/trace.go:171","msg":"trace[1552205763] transaction","detail":"{read_only:false; response_revision:5655; number_of_response:1; }","duration":"283.820649ms","start":"2026-07-16T16:03:56.417809Z","end":"2026-07-16T16:03:56.70163Z","steps":["trace[1552205763] 'process raft request' (duration: 283.605695ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:03:56.746306Z","caller":"traceutil/trace.go:171","msg":"trace[1187202563] linearizableReadLoop","detail":"{readStateIndex:5865; appliedIndex:5863; }","duration":"271.132384ms","start":"2026-07-16T16:03:56.475147Z","end":"2026-07-16T16:03:56.746279Z","steps":["trace[1187202563] 'read index received' (duration: 226.289219ms)","trace[1187202563] 'applied index is now lower than readState.Index' (duration: 44.841865ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:03:56.746448Z","caller":"traceutil/trace.go:171","msg":"trace[349651995] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"325.83662ms","start":"2026-07-16T16:03:56.420568Z","end":"2026-07-16T16:03:56.746404Z","steps":["trace[349651995] 'process raft request' (duration: 325.554135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:03:56.746793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:03:56.420559Z","time spent":"325.973293ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:03:56.746792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.625793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-hnc9z\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-07-16T16:03:56.746854Z","caller":"traceutil/trace.go:171","msg":"trace[436369476] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-hnc9z; range_end:; response_count:1; response_revision:5656; }","duration":"271.709805ms","start":"2026-07-16T16:03:56.475126Z","end":"2026-07-16T16:03:56.746836Z","steps":["trace[436369476] 'agreement among raft nodes before linearized reading' (duration: 271.299838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:04:05.051204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.982899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17476"} {"level":"info","ts":"2026-07-16T16:04:05.05147Z","caller":"traceutil/trace.go:171","msg":"trace[1305612380] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5853; }","duration":"115.279095ms","start":"2026-07-16T16:04:04.936165Z","end":"2026-07-16T16:04:05.051444Z","steps":["trace[1305612380] 'range keys from in-memory index tree' (duration: 114.712374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:04:05.262173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.540201ms","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-07-16T16:04:05.262303Z","caller":"traceutil/trace.go:171","msg":"trace[1151798362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5854; }","duration":"100.755535ms","start":"2026-07-16T16:04:05.161511Z","end":"2026-07-16T16:04:05.262267Z","steps":["trace[1151798362] 'range keys from in-memory index tree' (duration: 100.278847ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:04:05.306442Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3012} {"level":"info","ts":"2026-07-16T16:04:05.423061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3012,"took":"113.967501ms","hash":3155243159,"current-db-size-bytes":55848960,"current-db-size":"56 MB","current-db-size-in-use-bytes":29917184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-16T16:04:05.42317Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3155243159,"revision":3012,"compact-revision":-1} {"level":"info","ts":"2026-07-16T16:09:05.312752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5855} {"level":"info","ts":"2026-07-16T16:09:05.397902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5855,"took":"82.776272ms","hash":2248784776,"current-db-size-bytes":55848960,"current-db-size":"56 MB","current-db-size-in-use-bytes":26279936,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-16T16:09:05.398007Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2248784776,"revision":5855,"compact-revision":3012} {"level":"info","ts":"2026-07-16T16:10:28.730041Z","caller":"traceutil/trace.go:171","msg":"trace[1177141143] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"192.971413ms","start":"2026-07-16T16:10:28.537033Z","end":"2026-07-16T16:10:28.730004Z","steps":["trace[1177141143] 'process raft request' (duration: 188.988307ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:10:39.72041Z","caller":"traceutil/trace.go:171","msg":"trace[665484340] transaction","detail":"{read_only:false; response_revision:8796; number_of_response:1; }","duration":"275.345738ms","start":"2026-07-16T16:10:39.44503Z","end":"2026-07-16T16:10:39.720376Z","steps":["trace[665484340] 'process raft request' (duration: 256.872333ms)","trace[665484340] 'compare' (duration: 18.279201ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:10:41.641465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.04186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-16T16:10:41.641548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.221807ms","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-07-16T16:10:41.641586Z","caller":"traceutil/trace.go:171","msg":"trace[2091556202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8806; }","duration":"132.234494ms","start":"2026-07-16T16:10:41.509328Z","end":"2026-07-16T16:10:41.641562Z","steps":["trace[2091556202] 'range keys from in-memory index tree' (duration: 131.705204ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:10:41.641659Z","caller":"traceutil/trace.go:171","msg":"trace[1482412096] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8806; }","duration":"261.39622ms","start":"2026-07-16T16:10:41.380233Z","end":"2026-07-16T16:10:41.641629Z","steps":["trace[1482412096] 'count revisions from in-memory index tree' (duration: 261.019791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:10:42.589326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.293732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-07-16T16:10:42.589429Z","caller":"traceutil/trace.go:171","msg":"trace[1785098326] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8810; }","duration":"124.435994ms","start":"2026-07-16T16:10:42.464965Z","end":"2026-07-16T16:10:42.589401Z","steps":["trace[1785098326] 'range keys from in-memory index tree' (duration: 124.071988ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:11:07.394596Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002461.wal"} {"level":"warn","ts":"2026-07-16T16:11:12.383359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.557876ms","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-07-16T16:11:12.383474Z","caller":"traceutil/trace.go:171","msg":"trace[616141678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9024; }","duration":"427.76325ms","start":"2026-07-16T16:11:11.955686Z","end":"2026-07-16T16:11:12.38345Z","steps":["trace[616141678] 'range keys from in-memory index tree' (duration: 427.309871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:11:12.383561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:11:11.955656Z","time spent":"427.883873ms","remote":"127.0.0.1:46040","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-07-16T16:11:12.384538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.278163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T16:11:12.384626Z","caller":"traceutil/trace.go:171","msg":"trace[866236409] linearizableReadLoop","detail":"{readStateIndex:9341; appliedIndex:9340; }","duration":"140.90686ms","start":"2026-07-16T16:11:12.243701Z","end":"2026-07-16T16:11:12.384608Z","steps":["trace[866236409] 'read index received' (duration: 49.281µs)","trace[866236409] 'applied index is now lower than readState.Index' (duration: 140.855729ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:11:12.384978Z","caller":"traceutil/trace.go:171","msg":"trace[1417270306] transaction","detail":"{read_only:false; response_revision:9025; number_of_response:1; }","duration":"418.955472ms","start":"2026-07-16T16:11:11.966008Z","end":"2026-07-16T16:11:12.384963Z","steps":["trace[1417270306] 'process raft request' (duration: 204.184889ms)","trace[1417270306] 'compare' (duration: 214.002598ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:11:12.385068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:11:11.96597Z","time spent":"419.049723ms","remote":"127.0.0.1:46040","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-07-16T16:11:12.385278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.568793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T16:11:12.38533Z","caller":"traceutil/trace.go:171","msg":"trace[160142842] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:9025; }","duration":"141.673435ms","start":"2026-07-16T16:11:12.243641Z","end":"2026-07-16T16:11:12.385315Z","steps":["trace[160142842] 'agreement among raft nodes before linearized reading' (duration: 141.592094ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:11:14.598738Z","caller":"traceutil/trace.go:171","msg":"trace[1790275337] transaction","detail":"{read_only:false; response_revision:9035; number_of_response:1; }","duration":"119.652993ms","start":"2026-07-16T16:11:14.479021Z","end":"2026-07-16T16:11:14.598674Z","steps":["trace[1790275337] 'process raft request' (duration: 89.184644ms)","trace[1790275337] 'compare' (duration: 30.111351ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:11:24.637509Z","caller":"traceutil/trace.go:171","msg":"trace[92191851] transaction","detail":"{read_only:false; response_revision:9127; number_of_response:1; }","duration":"139.098417ms","start":"2026-07-16T16:11:24.498381Z","end":"2026-07-16T16:11:24.637479Z","steps":["trace[92191851] 'process raft request' (duration: 138.761541ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:11:24.637643Z","caller":"traceutil/trace.go:171","msg":"trace[465202408] transaction","detail":"{read_only:false; response_revision:9128; number_of_response:1; }","duration":"127.3351ms","start":"2026-07-16T16:11:24.510272Z","end":"2026-07-16T16:11:24.637607Z","steps":["trace[465202408] 'process raft request' (duration: 127.126365ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:11:24.646795Z","caller":"traceutil/trace.go:171","msg":"trace[963346899] linearizableReadLoop","detail":"{readStateIndex:9451; appliedIndex:9448; }","duration":"111.492144ms","start":"2026-07-16T16:11:24.535277Z","end":"2026-07-16T16:11:24.646769Z","steps":["trace[963346899] 'read index received' (duration: 101.872148ms)","trace[963346899] 'applied index is now lower than readState.Index' (duration: 9.618496ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:11:24.64695Z","caller":"traceutil/trace.go:171","msg":"trace[1814441102] transaction","detail":"{read_only:false; response_revision:9129; number_of_response:1; }","duration":"111.647357ms","start":"2026-07-16T16:11:24.535254Z","end":"2026-07-16T16:11:24.646902Z","steps":["trace[1814441102] 'process raft request' (duration: 111.361992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:11:24.647425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.103447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-l8vj4\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-07-16T16:11:24.647517Z","caller":"traceutil/trace.go:171","msg":"trace[1414737332] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-l8vj4; range_end:; response_count:1; response_revision:9129; }","duration":"112.269851ms","start":"2026-07-16T16:11:24.535229Z","end":"2026-07-16T16:11:24.647499Z","steps":["trace[1414737332] 'agreement among raft nodes before linearized reading' (duration: 111.682909ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:11:25.284759Z","caller":"traceutil/trace.go:171","msg":"trace[358583720] linearizableReadLoop","detail":"{readStateIndex:9457; appliedIndex:9456; }","duration":"101.437681ms","start":"2026-07-16T16:11:25.183302Z","end":"2026-07-16T16:11:25.284739Z","steps":["trace[358583720] 'read index received' (duration: 16.662422ms)","trace[358583720] 'applied index is now lower than readState.Index' (duration: 84.774709ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:11:25.284956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.650844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ksbqt\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-16T16:11:25.284976Z","caller":"traceutil/trace.go:171","msg":"trace[88327859] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ksbqt; range_end:; response_count:1; response_revision:9135; }","duration":"101.732266ms","start":"2026-07-16T16:11:25.183237Z","end":"2026-07-16T16:11:25.284969Z","steps":["trace[88327859] 'agreement among raft nodes before linearized reading' (duration: 101.589343ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:11:25.285201Z","caller":"traceutil/trace.go:171","msg":"trace[1523014823] transaction","detail":"{read_only:false; response_revision:9135; number_of_response:1; }","duration":"202.318008ms","start":"2026-07-16T16:11:25.082824Z","end":"2026-07-16T16:11:25.285142Z","steps":["trace[1523014823] 'process raft request' (duration: 117.187453ms)","trace[1523014823] 'compare' (duration: 84.641346ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:11:25.350518Z","caller":"traceutil/trace.go:171","msg":"trace[653097242] transaction","detail":"{read_only:false; response_revision:9136; number_of_response:1; }","duration":"114.661696ms","start":"2026-07-16T16:11:25.235817Z","end":"2026-07-16T16:11:25.350479Z","steps":["trace[653097242] 'process raft request' (duration: 114.452012ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:11:25.511436Z","caller":"traceutil/trace.go:171","msg":"trace[589791526] linearizableReadLoop","detail":"{readStateIndex:9459; appliedIndex:9457; }","duration":"220.796816ms","start":"2026-07-16T16:11:25.290608Z","end":"2026-07-16T16:11:25.511405Z","steps":["trace[589791526] 'read index received' (duration: 59.740665ms)","trace[589791526] 'applied index is now lower than readState.Index' (duration: 161.054731ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:11:25.511717Z","caller":"traceutil/trace.go:171","msg":"trace[1878743658] transaction","detail":"{read_only:false; response_revision:9137; number_of_response:1; }","duration":"230.586386ms","start":"2026-07-16T16:11:25.281072Z","end":"2026-07-16T16:11:25.511658Z","steps":["trace[1878743658] 'process raft request' (duration: 133.751385ms)","trace[1878743658] 'compare' (duration: 88.758375ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:11:25.512117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.482829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228772"} {"level":"info","ts":"2026-07-16T16:11:25.512215Z","caller":"traceutil/trace.go:171","msg":"trace[792799705] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:9137; }","duration":"221.610302ms","start":"2026-07-16T16:11:25.290586Z","end":"2026-07-16T16:11:25.512197Z","steps":["trace[792799705] 'agreement among raft nodes before linearized reading' (duration: 221.080122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:11:25.550227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.968443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T16:11:25.550314Z","caller":"traceutil/trace.go:171","msg":"trace[1042588321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9138; }","duration":"245.102346ms","start":"2026-07-16T16:11:25.305191Z","end":"2026-07-16T16:11:25.550293Z","steps":["trace[1042588321] 'agreement among raft nodes before linearized reading' (duration: 244.929343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:11:25.551492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.945334ms","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-07-16T16:11:25.551582Z","caller":"traceutil/trace.go:171","msg":"trace[494213305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9138; }","duration":"140.080806ms","start":"2026-07-16T16:11:25.41148Z","end":"2026-07-16T16:11:25.551561Z","steps":["trace[494213305] 'agreement among raft nodes before linearized reading' (duration: 139.7606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:11:25.551654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.440933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T16:11:25.55171Z","caller":"traceutil/trace.go:171","msg":"trace[505785031] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:9138; }","duration":"198.523245ms","start":"2026-07-16T16:11:25.353171Z","end":"2026-07-16T16:11:25.551695Z","steps":["trace[505785031] 'agreement among raft nodes before linearized reading' (duration: 198.304171ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:11:26.3437Z","caller":"traceutil/trace.go:171","msg":"trace[2038750760] linearizableReadLoop","detail":"{readStateIndex:9465; appliedIndex:9464; }","duration":"110.279571ms","start":"2026-07-16T16:11:26.233397Z","end":"2026-07-16T16:11:26.343676Z","steps":["trace[2038750760] 'read index received' (duration: 31.54726ms)","trace[2038750760] 'applied index is now lower than readState.Index' (duration: 78.730881ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:11:26.343795Z","caller":"traceutil/trace.go:171","msg":"trace[1133415873] transaction","detail":"{read_only:false; response_revision:9143; number_of_response:1; }","duration":"116.123064ms","start":"2026-07-16T16:11:26.22762Z","end":"2026-07-16T16:11:26.343744Z","steps":["trace[1133415873] 'process raft request' (duration: 37.436634ms)","trace[1133415873] 'compare' (duration: 78.502886ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:11:26.343894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.477525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-07-16T16:11:26.343944Z","caller":"traceutil/trace.go:171","msg":"trace[1702447855] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:9143; }","duration":"110.606827ms","start":"2026-07-16T16:11:26.233323Z","end":"2026-07-16T16:11:26.34393Z","steps":["trace[1702447855] 'agreement among raft nodes before linearized reading' (duration: 110.452954ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:12:18.06671Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-16T16:12:18.073918Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-16T16:12:18.074041Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-16T16:12:43.616589Z","caller":"traceutil/trace.go:171","msg":"trace[271621642] transaction","detail":"{read_only:false; response_revision:9896; number_of_response:1; }","duration":"127.659776ms","start":"2026-07-16T16:12:43.488894Z","end":"2026-07-16T16:12:43.616554Z","steps":["trace[271621642] 'process raft request' (duration: 127.447062ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:14:05.320762Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8069} {"level":"info","ts":"2026-07-16T16:14:05.384408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8069,"took":"61.430915ms","hash":4121914929,"current-db-size-bytes":55848960,"current-db-size":"56 MB","current-db-size-in-use-bytes":28110848,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-16T16:14:05.384481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4121914929,"revision":8069,"compact-revision":5855} {"level":"info","ts":"2026-07-16T16:18:19.187485Z","caller":"traceutil/trace.go:171","msg":"trace[993221997] transaction","detail":"{read_only:false; response_revision:12310; number_of_response:1; }","duration":"174.201044ms","start":"2026-07-16T16:18:19.013252Z","end":"2026-07-16T16:18:19.187453Z","steps":["trace[993221997] 'process raft request' (duration: 173.99157ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:18:19.324062Z","caller":"traceutil/trace.go:171","msg":"trace[1338040319] transaction","detail":"{read_only:false; response_revision:12311; number_of_response:1; }","duration":"308.50527ms","start":"2026-07-16T16:18:19.015517Z","end":"2026-07-16T16:18:19.324023Z","steps":["trace[1338040319] 'process raft request' (duration: 295.01756ms)","trace[1338040319] 'compare' (duration: 13.167605ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:18:19.324128Z","caller":"traceutil/trace.go:171","msg":"trace[1639958060] transaction","detail":"{read_only:false; response_revision:12312; number_of_response:1; }","duration":"225.901449ms","start":"2026-07-16T16:18:19.098169Z","end":"2026-07-16T16:18:19.32407Z","steps":["trace[1639958060] 'process raft request' (duration: 225.678715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:18:19.324279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:18:19.015485Z","time spent":"308.682864ms","remote":"127.0.0.1:46040","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-07-16T16:18:19.324397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.707418ms","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-07-16T16:18:19.324509Z","caller":"traceutil/trace.go:171","msg":"trace[1230366776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12312; }","duration":"277.886231ms","start":"2026-07-16T16:18:19.046602Z","end":"2026-07-16T16:18:19.324488Z","steps":["trace[1230366776] 'agreement among raft nodes before linearized reading' (duration: 277.555875ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:18:19.324737Z","caller":"traceutil/trace.go:171","msg":"trace[791044102] linearizableReadLoop","detail":"{readStateIndex:12734; appliedIndex:12732; }","duration":"277.31711ms","start":"2026-07-16T16:18:19.046663Z","end":"2026-07-16T16:18:19.323981Z","steps":["trace[791044102] 'read index received' (duration: 140.716179ms)","trace[791044102] 'applied index is now lower than readState.Index' (duration: 136.599271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:18:19.325094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.641587ms","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-07-16T16:18:19.32514Z","caller":"traceutil/trace.go:171","msg":"trace[1788556117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12312; }","duration":"277.78497ms","start":"2026-07-16T16:18:19.047342Z","end":"2026-07-16T16:18:19.325127Z","steps":["trace[1788556117] 'agreement among raft nodes before linearized reading' (duration: 277.617727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:18:19.325314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.390893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-backup-785bb65dbb-2xmz6.18c2d16cb9c7e134\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-07-16T16:18:19.325459Z","caller":"traceutil/trace.go:171","msg":"trace[628258437] range","detail":"{range_begin:/registry/events/openstack/cinder-backup-785bb65dbb-2xmz6.18c2d16cb9c7e134; range_end:; response_count:1; response_revision:12312; }","duration":"275.604067ms","start":"2026-07-16T16:18:19.049822Z","end":"2026-07-16T16:18:19.325426Z","steps":["trace[628258437] 'agreement among raft nodes before linearized reading' (duration: 275.20323ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:19:05.332774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10437} {"level":"info","ts":"2026-07-16T16:19:05.414381Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10437,"took":"78.563513ms","hash":2655470719,"current-db-size-bytes":55848960,"current-db-size":"56 MB","current-db-size-in-use-bytes":26062848,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-16T16:19:05.414486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2655470719,"revision":10437,"compact-revision":8069} {"level":"info","ts":"2026-07-16T16:24:05.346209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12638} {"level":"info","ts":"2026-07-16T16:24:05.425028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12638,"took":"75.903562ms","hash":1739782945,"current-db-size-bytes":55848960,"current-db-size":"56 MB","current-db-size-in-use-bytes":28028928,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-16T16:24:05.425084Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1739782945,"revision":12638,"compact-revision":10437} {"level":"warn","ts":"2026-07-16T16:25:11.566048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.566621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T16:25:11.566169Z","caller":"traceutil/trace.go:171","msg":"trace[516266420] transaction","detail":"{read_only:false; response_revision:15046; number_of_response:1; }","duration":"168.268093ms","start":"2026-07-16T16:25:11.397885Z","end":"2026-07-16T16:25:11.566153Z","steps":["trace[516266420] 'process raft request' (duration: 44.516419ms)","trace[516266420] 'compare' (duration: 123.343047ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:25:22.286093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.38088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-16T16:25:22.286202Z","caller":"traceutil/trace.go:171","msg":"trace[2089640598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15220; }","duration":"121.566133ms","start":"2026-07-16T16:25:22.164612Z","end":"2026-07-16T16:25:22.286178Z","steps":["trace[2089640598] 'range keys from in-memory index tree' (duration: 121.136855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:25:22.286566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.334022ms","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-07-16T16:25:22.286593Z","caller":"traceutil/trace.go:171","msg":"trace[1308157415] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:15220; }","duration":"116.407024ms","start":"2026-07-16T16:25:22.170177Z","end":"2026-07-16T16:25:22.286584Z","steps":["trace[1308157415] 'count revisions from in-memory index tree' (duration: 116.23234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:25:22.286704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.34899ms","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-07-16T16:25:22.286785Z","caller":"traceutil/trace.go:171","msg":"trace[1953658438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15220; }","duration":"119.502613ms","start":"2026-07-16T16:25:22.167265Z","end":"2026-07-16T16:25:22.286767Z","steps":["trace[1953658438] 'range keys from in-memory index tree' (duration: 119.124166ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:25:22.486471Z","caller":"traceutil/trace.go:171","msg":"trace[1031418665] linearizableReadLoop","detail":"{readStateIndex:15746; appliedIndex:15744; }","duration":"183.154991ms","start":"2026-07-16T16:25:22.303291Z","end":"2026-07-16T16:25:22.486446Z","steps":["trace[1031418665] 'read index received' (duration: 41.50922ms)","trace[1031418665] 'applied index is now lower than readState.Index' (duration: 141.644561ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:25:22.486523Z","caller":"traceutil/trace.go:171","msg":"trace[1297558599] transaction","detail":"{read_only:false; response_revision:15222; number_of_response:1; }","duration":"195.605391ms","start":"2026-07-16T16:25:22.290896Z","end":"2026-07-16T16:25:22.486502Z","steps":["trace[1297558599] 'process raft request' (duration: 141.511508ms)","trace[1297558599] 'compare' (duration: 53.906789ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:25:22.48669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.360244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T16:25:22.486801Z","caller":"traceutil/trace.go:171","msg":"trace[943520501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15222; }","duration":"183.532698ms","start":"2026-07-16T16:25:22.303243Z","end":"2026-07-16T16:25:22.486776Z","steps":["trace[943520501] 'agreement among raft nodes before linearized reading' (duration: 183.314344ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:26:55.759542Z","caller":"traceutil/trace.go:171","msg":"trace[1706059198] transaction","detail":"{read_only:false; response_revision:16276; number_of_response:1; }","duration":"101.594468ms","start":"2026-07-16T16:26:55.657902Z","end":"2026-07-16T16:26:55.759497Z","steps":["trace[1706059198] 'process raft request' (duration: 101.445565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:26:55.954777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.371439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-q5f8p\" ","response":"range_response_count:1 size:22092"} {"level":"info","ts":"2026-07-16T16:26:55.954924Z","caller":"traceutil/trace.go:171","msg":"trace[45579415] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-q5f8p; range_end:; response_count:1; response_revision:16276; }","duration":"139.589613ms","start":"2026-07-16T16:26:55.815312Z","end":"2026-07-16T16:26:55.954902Z","steps":["trace[45579415] 'range keys from in-memory index tree' (duration: 139.182975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:26:55.955694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.84864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-q5f8p\" ","response":"range_response_count:1 size:22092"} {"level":"info","ts":"2026-07-16T16:26:55.95577Z","caller":"traceutil/trace.go:171","msg":"trace[1287989005] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-q5f8p; range_end:; response_count:1; response_revision:16276; }","duration":"137.972001ms","start":"2026-07-16T16:26:55.817782Z","end":"2026-07-16T16:26:55.955754Z","steps":["trace[1287989005] 'range keys from in-memory index tree' (duration: 136.623866ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:29:05.353215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14631} {"level":"info","ts":"2026-07-16T16:29:05.434343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14631,"took":"77.637059ms","hash":3085271652,"current-db-size-bytes":55848960,"current-db-size":"56 MB","current-db-size-in-use-bytes":31641600,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-16T16:29:05.434415Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3085271652,"revision":14631,"compact-revision":12638} {"level":"info","ts":"2026-07-16T16:32:46.661701Z","caller":"traceutil/trace.go:171","msg":"trace[1205614662] transaction","detail":"{read_only:false; response_revision:18822; number_of_response:1; }","duration":"119.607541ms","start":"2026-07-16T16:32:46.542052Z","end":"2026-07-16T16:32:46.661659Z","steps":["trace[1205614662] 'process raft request' (duration: 119.236873ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:32:46.80341Z","caller":"traceutil/trace.go:171","msg":"trace[2423240] transaction","detail":"{read_only:false; response_revision:18823; number_of_response:1; }","duration":"123.472331ms","start":"2026-07-16T16:32:46.679904Z","end":"2026-07-16T16:32:46.803377Z","steps":["trace[2423240] 'process raft request' (duration: 123.172415ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:32:47.135972Z","caller":"traceutil/trace.go:171","msg":"trace[1104360479] transaction","detail":"{read_only:false; response_revision:18825; number_of_response:1; }","duration":"227.833511ms","start":"2026-07-16T16:32:46.908115Z","end":"2026-07-16T16:32:47.135949Z","steps":["trace[1104360479] 'process raft request' (duration: 227.671828ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:32:47.407295Z","caller":"traceutil/trace.go:171","msg":"trace[1456896678] linearizableReadLoop","detail":"{readStateIndex:19468; appliedIndex:19467; }","duration":"496.254939ms","start":"2026-07-16T16:32:46.910983Z","end":"2026-07-16T16:32:47.407238Z","steps":["trace[1456896678] 'read index received' (duration: 225.57174ms)","trace[1456896678] 'applied index is now lower than readState.Index' (duration: 270.681589ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:32:47.407345Z","caller":"traceutil/trace.go:171","msg":"trace[612167946] transaction","detail":"{read_only:false; response_revision:18826; number_of_response:1; }","duration":"498.937538ms","start":"2026-07-16T16:32:46.908364Z","end":"2026-07-16T16:32:47.407301Z","steps":["trace[612167946] 'process raft request' (duration: 450.552619ms)","trace[612167946] 'compare' (duration: 47.966672ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:32:47.407542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:32:46.908351Z","time spent":"499.087581ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:32:47.407638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.640037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T16:32:47.407726Z","caller":"traceutil/trace.go:171","msg":"trace[539049787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18826; }","duration":"496.801599ms","start":"2026-07-16T16:32:46.910909Z","end":"2026-07-16T16:32:47.40771Z","steps":["trace[539049787] 'agreement among raft nodes before linearized reading' (duration: 496.548614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:32:47.407766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:32:46.910875Z","time spent":"496.8812ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T16:32:47.407917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.894026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:32:47.40795Z","caller":"traceutil/trace.go:171","msg":"trace[819073374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18826; }","duration":"100.924357ms","start":"2026-07-16T16:32:47.307016Z","end":"2026-07-16T16:32:47.407941Z","steps":["trace[819073374] 'agreement among raft nodes before linearized reading' (duration: 100.873026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:32:47.408001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.192753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-07-16T16:32:47.408109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.159134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6h985\" ","response":"range_response_count:1 size:9789"} {"level":"info","ts":"2026-07-16T16:32:47.408173Z","caller":"traceutil/trace.go:171","msg":"trace[832716740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18826; }","duration":"486.412269ms","start":"2026-07-16T16:32:46.921701Z","end":"2026-07-16T16:32:47.408113Z","steps":["trace[832716740] 'agreement among raft nodes before linearized reading' (duration: 485.798237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:32:47.408269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:32:46.921681Z","time spent":"486.573451ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-16T16:32:47.408275Z","caller":"traceutil/trace.go:171","msg":"trace[1626058609] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6h985; range_end:; response_count:1; response_revision:18826; }","duration":"180.383118ms","start":"2026-07-16T16:32:47.227833Z","end":"2026-07-16T16:32:47.408216Z","steps":["trace[1626058609] 'agreement among raft nodes before linearized reading' (duration: 179.873099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:32:47.408151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.56962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:32:47.408388Z","caller":"traceutil/trace.go:171","msg":"trace[1849870468] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:18826; }","duration":"231.970077ms","start":"2026-07-16T16:32:47.176403Z","end":"2026-07-16T16:32:47.408373Z","steps":["trace[1849870468] 'agreement among raft nodes before linearized reading' (duration: 231.689032ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:33:40.999202Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":20005,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-16T16:33:41.006537Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20005} {"level":"info","ts":"2026-07-16T16:33:41.006714Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15005} {"level":"info","ts":"2026-07-16T16:34:05.366334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17072} {"level":"info","ts":"2026-07-16T16:34:05.462143Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17072,"took":"92.232172ms","hash":2454409411,"current-db-size-bytes":55848960,"current-db-size":"56 MB","current-db-size-in-use-bytes":34193408,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-16T16:34:05.46232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2454409411,"revision":17072,"compact-revision":14631} {"level":"info","ts":"2026-07-16T16:39:03.151719Z","caller":"traceutil/trace.go:171","msg":"trace[1049728518] transaction","detail":"{read_only:false; response_revision:21690; number_of_response:1; }","duration":"125.274659ms","start":"2026-07-16T16:39:03.026401Z","end":"2026-07-16T16:39:03.151676Z","steps":["trace[1049728518] 'process raft request' (duration: 114.467341ms)","trace[1049728518] 'compare' (duration: 10.544392ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:39:05.371818Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19678} {"level":"info","ts":"2026-07-16T16:39:05.486617Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19678,"took":"110.500434ms","hash":882766859,"current-db-size-bytes":55848960,"current-db-size":"56 MB","current-db-size-in-use-bytes":29638656,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-16T16:39:05.486712Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":882766859,"revision":19678,"compact-revision":17072} {"level":"warn","ts":"2026-07-16T16:39:23.867921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.386247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:39:23.868023Z","caller":"traceutil/trace.go:171","msg":"trace[660440653] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21794; }","duration":"110.550471ms","start":"2026-07-16T16:39:23.75745Z","end":"2026-07-16T16:39:23.868001Z","steps":["trace[660440653] 'range keys from in-memory index tree' (duration: 110.242465ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:39:29.463309Z","caller":"traceutil/trace.go:171","msg":"trace[1986940789] linearizableReadLoop","detail":"{readStateIndex:22568; appliedIndex:22567; }","duration":"157.289663ms","start":"2026-07-16T16:39:29.305994Z","end":"2026-07-16T16:39:29.463283Z","steps":["trace[1986940789] 'read index received' (duration: 157.092869ms)","trace[1986940789] 'applied index is now lower than readState.Index' (duration: 195.614µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:39:29.463401Z","caller":"traceutil/trace.go:171","msg":"trace[2081913967] transaction","detail":"{read_only:false; response_revision:21824; number_of_response:1; }","duration":"214.35535ms","start":"2026-07-16T16:39:29.249Z","end":"2026-07-16T16:39:29.463355Z","steps":["trace[2081913967] 'process raft request' (duration: 214.103306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:39:29.463516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.695841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T16:39:29.463514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.500566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:39:29.463573Z","caller":"traceutil/trace.go:171","msg":"trace[157087819] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:21824; }","duration":"117.811073ms","start":"2026-07-16T16:39:29.345744Z","end":"2026-07-16T16:39:29.463555Z","steps":["trace[157087819] 'agreement among raft nodes before linearized reading' (duration: 117.687851ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:39:29.463616Z","caller":"traceutil/trace.go:171","msg":"trace[2095616655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21824; }","duration":"157.649669ms","start":"2026-07-16T16:39:29.305939Z","end":"2026-07-16T16:39:29.463589Z","steps":["trace[2095616655] 'agreement among raft nodes before linearized reading' (duration: 157.487396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:39:29.733727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.18347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-16T16:39:29.733829Z","caller":"traceutil/trace.go:171","msg":"trace[2016714381] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:21824; }","duration":"262.357173ms","start":"2026-07-16T16:39:29.471451Z","end":"2026-07-16T16:39:29.733808Z","steps":["trace[2016714381] 'count revisions from in-memory index tree' (duration: 262.069968ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:39:29.874059Z","caller":"traceutil/trace.go:171","msg":"trace[2020489694] transaction","detail":"{read_only:false; response_revision:21825; number_of_response:1; }","duration":"135.483985ms","start":"2026-07-16T16:39:29.738512Z","end":"2026-07-16T16:39:29.873996Z","steps":["trace[2020489694] 'process raft request' (duration: 135.239411ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:39:29.885822Z","caller":"traceutil/trace.go:171","msg":"trace[835826245] transaction","detail":"{read_only:false; response_revision:21826; number_of_response:1; }","duration":"146.910223ms","start":"2026-07-16T16:39:29.738885Z","end":"2026-07-16T16:39:29.885795Z","steps":["trace[835826245] 'process raft request' (duration: 146.79654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:39:30.637603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.369621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-16T16:39:30.637719Z","caller":"traceutil/trace.go:171","msg":"trace[561377109] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:21827; }","duration":"130.550256ms","start":"2026-07-16T16:39:30.507148Z","end":"2026-07-16T16:39:30.637698Z","steps":["trace[561377109] 'count revisions from in-memory index tree' (duration: 130.215549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:39:30.637805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.266103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-16T16:39:30.638339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.636679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T16:39:30.638451Z","caller":"traceutil/trace.go:171","msg":"trace[1242855455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21827; }","duration":"217.824783ms","start":"2026-07-16T16:39:30.420604Z","end":"2026-07-16T16:39:30.638429Z","steps":["trace[1242855455] 'range keys from in-memory index tree' (duration: 217.342274ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:39:30.637915Z","caller":"traceutil/trace.go:171","msg":"trace[1582309932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21827; }","duration":"217.435106ms","start":"2026-07-16T16:39:30.420463Z","end":"2026-07-16T16:39:30.637898Z","steps":["trace[1582309932] 'range keys from in-memory index tree' (duration: 216.882405ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:39:30.78572Z","caller":"traceutil/trace.go:171","msg":"trace[1460079258] transaction","detail":"{read_only:false; response_revision:21829; number_of_response:1; }","duration":"141.026665ms","start":"2026-07-16T16:39:30.644668Z","end":"2026-07-16T16:39:30.785694Z","steps":["trace[1460079258] 'process raft request' (duration: 140.440225ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:43:37.410728Z","caller":"traceutil/trace.go:171","msg":"trace[1206361348] transaction","detail":"{read_only:false; response_revision:24353; number_of_response:1; }","duration":"223.099843ms","start":"2026-07-16T16:43:37.187594Z","end":"2026-07-16T16:43:37.410694Z","steps":["trace[1206361348] 'process raft request' (duration: 222.890149ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:43:37.498604Z","caller":"traceutil/trace.go:171","msg":"trace[608986625] transaction","detail":"{read_only:false; response_revision:24356; number_of_response:1; }","duration":"216.055719ms","start":"2026-07-16T16:43:37.282508Z","end":"2026-07-16T16:43:37.498563Z","steps":["trace[608986625] 'process raft request' (duration: 215.944477ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:43:37.498593Z","caller":"traceutil/trace.go:171","msg":"trace[1833968502] transaction","detail":"{read_only:false; response_revision:24354; number_of_response:1; }","duration":"309.946358ms","start":"2026-07-16T16:43:37.1886Z","end":"2026-07-16T16:43:37.498546Z","steps":["trace[1833968502] 'process raft request' (duration: 309.629782ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:43:37.498782Z","caller":"traceutil/trace.go:171","msg":"trace[179816659] linearizableReadLoop","detail":"{readStateIndex:25177; appliedIndex:25174; }","duration":"299.300224ms","start":"2026-07-16T16:43:37.199469Z","end":"2026-07-16T16:43:37.498769Z","steps":["trace[179816659] 'read index received' (duration: 211.064553ms)","trace[179816659] 'applied index is now lower than readState.Index' (duration: 88.233991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:43:37.498849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:43:37.188585Z","time spent":"310.151401ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:43:37.498883Z","caller":"traceutil/trace.go:171","msg":"trace[257865335] transaction","detail":"{read_only:false; response_revision:24357; number_of_response:1; }","duration":"116.851348ms","start":"2026-07-16T16:43:37.38199Z","end":"2026-07-16T16:43:37.498841Z","steps":["trace[257865335] 'process raft request' (duration: 116.508142ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:43:37.498627Z","caller":"traceutil/trace.go:171","msg":"trace[457472896] transaction","detail":"{read_only:false; response_revision:24355; number_of_response:1; }","duration":"309.55243ms","start":"2026-07-16T16:43:37.189027Z","end":"2026-07-16T16:43:37.49858Z","steps":["trace[457472896] 'process raft request' (duration: 309.372627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:43:37.498974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.167381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T16:43:37.498995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.518498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-16T16:43:37.49905Z","caller":"traceutil/trace.go:171","msg":"trace[228223180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24357; }","duration":"299.646071ms","start":"2026-07-16T16:43:37.199391Z","end":"2026-07-16T16:43:37.499037Z","steps":["trace[228223180] 'agreement among raft nodes before linearized reading' (duration: 299.432967ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:43:37.499017Z","caller":"traceutil/trace.go:171","msg":"trace[119870465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24357; }","duration":"193.275882ms","start":"2026-07-16T16:43:37.305727Z","end":"2026-07-16T16:43:37.499003Z","steps":["trace[119870465] 'agreement among raft nodes before linearized reading' (duration: 193.15241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:43:37.499142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:43:37.189009Z","time spent":"310.001668ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:44:05.384884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21700} {"level":"info","ts":"2026-07-16T16:44:05.493194Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21700,"took":"103.742028ms","hash":4048197905,"current-db-size-bytes":56270848,"current-db-size":"56 MB","current-db-size-in-use-bytes":42217472,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-16T16:44:05.493261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4048197905,"revision":21700,"compact-revision":19678} {"level":"info","ts":"2026-07-16T16:44:22.326501Z","caller":"traceutil/trace.go:171","msg":"trace[2028276854] linearizableReadLoop","detail":"{readStateIndex:25607; appliedIndex:25606; }","duration":"106.737345ms","start":"2026-07-16T16:44:22.219733Z","end":"2026-07-16T16:44:22.32647Z","steps":["trace[2028276854] 'read index received' (duration: 106.566271ms)","trace[2028276854] 'applied index is now lower than readState.Index' (duration: 169.924µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:44:22.32659Z","caller":"traceutil/trace.go:171","msg":"trace[2021353019] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"114.471603ms","start":"2026-07-16T16:44:22.212076Z","end":"2026-07-16T16:44:22.326548Z","steps":["trace[2021353019] 'process raft request' (duration: 114.195138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:44:22.326722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.963449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:180"} {"level":"info","ts":"2026-07-16T16:44:22.326802Z","caller":"traceutil/trace.go:171","msg":"trace[445945151] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:24774; }","duration":"107.092141ms","start":"2026-07-16T16:44:22.219689Z","end":"2026-07-16T16:44:22.326781Z","steps":["trace[445945151] 'agreement among raft nodes before linearized reading' (duration: 106.938269ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:44:22.477195Z","caller":"traceutil/trace.go:171","msg":"trace[1421398206] transaction","detail":"{read_only:false; response_revision:24780; number_of_response:1; }","duration":"106.635944ms","start":"2026-07-16T16:44:22.370526Z","end":"2026-07-16T16:44:22.477162Z","steps":["trace[1421398206] 'process raft request' (duration: 106.373298ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:45:07.724722Z","caller":"traceutil/trace.go:171","msg":"trace[866012540] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"124.75443ms","start":"2026-07-16T16:45:07.599928Z","end":"2026-07-16T16:45:07.724683Z","steps":["trace[866012540] 'process raft request' (duration: 124.508045ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:45:07.997911Z","caller":"traceutil/trace.go:171","msg":"trace[75900632] linearizableReadLoop","detail":"{readStateIndex:26170; appliedIndex:26169; }","duration":"254.808302ms","start":"2026-07-16T16:45:07.74308Z","end":"2026-07-16T16:45:07.997888Z","steps":["trace[75900632] 'read index received' (duration: 254.676329ms)","trace[75900632] 'applied index is now lower than readState.Index' (duration: 130.733µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:45:07.997948Z","caller":"traceutil/trace.go:171","msg":"trace[1809469205] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"261.309515ms","start":"2026-07-16T16:45:07.73661Z","end":"2026-07-16T16:45:07.99792Z","steps":["trace[1809469205] 'process raft request' (duration: 261.114672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:45:07.998053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.945244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:45:07.99809Z","caller":"traceutil/trace.go:171","msg":"trace[586965994] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25324; }","duration":"255.026775ms","start":"2026-07-16T16:45:07.74305Z","end":"2026-07-16T16:45:07.998077Z","steps":["trace[586965994] 'agreement among raft nodes before linearized reading' (duration: 254.931384ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:45:08.149948Z","caller":"traceutil/trace.go:171","msg":"trace[1366347311] linearizableReadLoop","detail":"{readStateIndex:26172; appliedIndex:26170; }","duration":"151.933901ms","start":"2026-07-16T16:45:07.99799Z","end":"2026-07-16T16:45:08.149924Z","steps":["trace[1366347311] 'read index received' (duration: 57.996881ms)","trace[1366347311] 'applied index is now lower than readState.Index' (duration: 93.93599ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:45:08.150102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.67573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:45:08.150081Z","caller":"traceutil/trace.go:171","msg":"trace[538702240] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"259.570382ms","start":"2026-07-16T16:45:07.89046Z","end":"2026-07-16T16:45:08.15003Z","steps":["trace[538702240] 'process raft request' (duration: 165.436239ms)","trace[538702240] 'compare' (duration: 93.652284ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:45:08.150167Z","caller":"traceutil/trace.go:171","msg":"trace[415946654] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:25326; }","duration":"193.771393ms","start":"2026-07-16T16:45:07.956384Z","end":"2026-07-16T16:45:08.150156Z","steps":["trace[415946654] 'agreement among raft nodes before linearized reading' (duration: 193.665131ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:45:08.150112Z","caller":"traceutil/trace.go:171","msg":"trace[1516993187] transaction","detail":"{read_only:false; response_revision:25326; number_of_response:1; }","duration":"247.528173ms","start":"2026-07-16T16:45:07.902527Z","end":"2026-07-16T16:45:08.150055Z","steps":["trace[1516993187] 'process raft request' (duration: 247.337409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:45:08.150244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.224746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-07-16T16:45:08.150257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.075144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:45:08.150329Z","caller":"traceutil/trace.go:171","msg":"trace[1596324433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25326; }","duration":"153.347027ms","start":"2026-07-16T16:45:07.996954Z","end":"2026-07-16T16:45:08.150301Z","steps":["trace[1596324433] 'agreement among raft nodes before linearized reading' (duration: 153.074422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:45:08.150346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.612558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:45:08.150377Z","caller":"traceutil/trace.go:171","msg":"trace[306744554] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:25326; }","duration":"198.243377ms","start":"2026-07-16T16:45:07.952109Z","end":"2026-07-16T16:45:08.150352Z","steps":["trace[306744554] 'agreement among raft nodes before linearized reading' (duration: 198.058804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:45:08.150403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.767136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:45:08.150428Z","caller":"traceutil/trace.go:171","msg":"trace[2028573160] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:25326; }","duration":"194.778561ms","start":"2026-07-16T16:45:07.955632Z","end":"2026-07-16T16:45:08.150411Z","steps":["trace[2028573160] 'agreement among raft nodes before linearized reading' (duration: 194.597428ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:45:08.150443Z","caller":"traceutil/trace.go:171","msg":"trace[823906954] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:25326; }","duration":"332.022131ms","start":"2026-07-16T16:45:07.818409Z","end":"2026-07-16T16:45:08.150431Z","steps":["trace[823906954] 'agreement among raft nodes before linearized reading' (duration: 331.958239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:45:08.150486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:45:07.818394Z","time spent":"332.079132ms","remote":"127.0.0.1:59638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true "} {"level":"warn","ts":"2026-07-16T16:45:08.150496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.416697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T16:45:08.150541Z","caller":"traceutil/trace.go:171","msg":"trace[280402704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25326; }","duration":"202.480528ms","start":"2026-07-16T16:45:07.948047Z","end":"2026-07-16T16:45:08.150527Z","steps":["trace[280402704] 'agreement among raft nodes before linearized reading' (duration: 202.270184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:45:08.150552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.867211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:45:08.150582Z","caller":"traceutil/trace.go:171","msg":"trace[1414433684] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:25326; }","duration":"197.898931ms","start":"2026-07-16T16:45:07.952674Z","end":"2026-07-16T16:45:08.150573Z","steps":["trace[1414433684] 'agreement among raft nodes before linearized reading' (duration: 197.84995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:45:08.150988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.096016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-07-16T16:45:08.151064Z","caller":"traceutil/trace.go:171","msg":"trace[292253625] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:25326; }","duration":"163.272208ms","start":"2026-07-16T16:45:07.987778Z","end":"2026-07-16T16:45:08.15105Z","steps":["trace[292253625] 'agreement among raft nodes before linearized reading' (duration: 162.193457ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:45:08.320685Z","caller":"traceutil/trace.go:171","msg":"trace[1751606712] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"163.060924ms","start":"2026-07-16T16:45:08.1576Z","end":"2026-07-16T16:45:08.320661Z","steps":["trace[1751606712] 'process raft request' (duration: 163.018984ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:45:08.320938Z","caller":"traceutil/trace.go:171","msg":"trace[627234425] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"227.392507ms","start":"2026-07-16T16:45:08.093516Z","end":"2026-07-16T16:45:08.320908Z","steps":["trace[627234425] 'process raft request' (duration: 177.003511ms)","trace[627234425] 'compare' (duration: 49.788595ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:45:08.321005Z","caller":"traceutil/trace.go:171","msg":"trace[2071562439] transaction","detail":"{read_only:false; response_revision:25328; number_of_response:1; }","duration":"164.099374ms","start":"2026-07-16T16:45:08.156854Z","end":"2026-07-16T16:45:08.320953Z","steps":["trace[2071562439] 'process raft request' (duration: 163.714137ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:45:08.321226Z","caller":"traceutil/trace.go:171","msg":"trace[1630885848] linearizableReadLoop","detail":"{readStateIndex:26173; appliedIndex:26172; }","duration":"171.19351ms","start":"2026-07-16T16:45:08.150022Z","end":"2026-07-16T16:45:08.321216Z","steps":["trace[1630885848] 'read index received' (duration: 120.491998ms)","trace[1630885848] 'applied index is now lower than readState.Index' (duration: 50.700292ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:45:08.321323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.934476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:45:08.321352Z","caller":"traceutil/trace.go:171","msg":"trace[203808537] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:25329; }","duration":"228.983827ms","start":"2026-07-16T16:45:08.092362Z","end":"2026-07-16T16:45:08.321346Z","steps":["trace[203808537] 'agreement among raft nodes before linearized reading' (duration: 228.915816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:45:08.321386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.925226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:45:08.321461Z","caller":"traceutil/trace.go:171","msg":"trace[1461401874] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:25329; }","duration":"229.227211ms","start":"2026-07-16T16:45:08.092216Z","end":"2026-07-16T16:45:08.321443Z","steps":["trace[1461401874] 'agreement among raft nodes before linearized reading' (duration: 229.055518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:45:08.321552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.267302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:45:08.321581Z","caller":"traceutil/trace.go:171","msg":"trace[2052645757] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:25329; }","duration":"229.338153ms","start":"2026-07-16T16:45:08.092237Z","end":"2026-07-16T16:45:08.321575Z","steps":["trace[2052645757] 'agreement among raft nodes before linearized reading' (duration: 229.272692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:45:08.321686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.723916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-07-16T16:45:08.321745Z","caller":"traceutil/trace.go:171","msg":"trace[1380833625] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:25329; }","duration":"224.814608ms","start":"2026-07-16T16:45:08.096917Z","end":"2026-07-16T16:45:08.321732Z","steps":["trace[1380833625] 'agreement among raft nodes before linearized reading' (duration: 224.591133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:45:08.321826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.361985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:45:08.321858Z","caller":"traceutil/trace.go:171","msg":"trace[205548627] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:25329; }","duration":"229.445726ms","start":"2026-07-16T16:45:08.092405Z","end":"2026-07-16T16:45:08.321851Z","steps":["trace[205548627] 'agreement among raft nodes before linearized reading' (duration: 229.394575ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:46:04.55777Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006847.wal"} {"level":"info","ts":"2026-07-16T16:47:20.958457Z","caller":"traceutil/trace.go:171","msg":"trace[916994490] transaction","detail":"{read_only:false; response_revision:26574; number_of_response:1; }","duration":"151.648545ms","start":"2026-07-16T16:47:20.806777Z","end":"2026-07-16T16:47:20.958426Z","steps":["trace[916994490] 'process raft request' (duration: 151.445211ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:20.999956Z","caller":"traceutil/trace.go:171","msg":"trace[263047913] transaction","detail":"{read_only:false; response_revision:26575; number_of_response:1; }","duration":"165.322077ms","start":"2026-07-16T16:47:20.834587Z","end":"2026-07-16T16:47:20.999909Z","steps":["trace[263047913] 'process raft request' (duration: 165.139524ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:26.500973Z","caller":"traceutil/trace.go:171","msg":"trace[1705654192] transaction","detail":"{read_only:false; response_revision:26628; number_of_response:1; }","duration":"251.919516ms","start":"2026-07-16T16:47:26.249021Z","end":"2026-07-16T16:47:26.500941Z","steps":["trace[1705654192] 'process raft request' (duration: 251.754002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:26.813288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.439522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T16:47:26.813457Z","caller":"traceutil/trace.go:171","msg":"trace[1104641109] linearizableReadLoop","detail":"{readStateIndex:27510; appliedIndex:27508; }","duration":"508.299158ms","start":"2026-07-16T16:47:26.305141Z","end":"2026-07-16T16:47:26.81344Z","steps":["trace[1104641109] 'read index received' (duration: 195.571416ms)","trace[1104641109] 'applied index is now lower than readState.Index' (duration: 312.726892ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:26.813458Z","caller":"traceutil/trace.go:171","msg":"trace[918108199] transaction","detail":"{read_only:false; response_revision:26629; number_of_response:1; }","duration":"528.797261ms","start":"2026-07-16T16:47:26.284634Z","end":"2026-07-16T16:47:26.813431Z","steps":["trace[918108199] 'process raft request' (duration: 353.151945ms)","trace[918108199] 'compare' (duration: 175.195756ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:47:26.81355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.414571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:47:26.813571Z","caller":"traceutil/trace.go:171","msg":"trace[854850763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26631; }","duration":"508.437491ms","start":"2026-07-16T16:47:26.305126Z","end":"2026-07-16T16:47:26.813563Z","steps":["trace[854850763] 'agreement among raft nodes before linearized reading' (duration: 508.36363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:26.813591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:26.305048Z","time spent":"508.538753ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T16:47:26.813612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:26.284611Z","time spent":"528.898183ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:47:26.813743Z","caller":"traceutil/trace.go:171","msg":"trace[1749040879] transaction","detail":"{read_only:false; response_revision:26631; number_of_response:1; }","duration":"336.582098ms","start":"2026-07-16T16:47:26.47714Z","end":"2026-07-16T16:47:26.813722Z","steps":["trace[1749040879] 'process raft request' (duration: 336.266662ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:26.813665Z","caller":"traceutil/trace.go:171","msg":"trace[150030719] transaction","detail":"{read_only:false; response_revision:26630; number_of_response:1; }","duration":"495.102505ms","start":"2026-07-16T16:47:26.318519Z","end":"2026-07-16T16:47:26.813622Z","steps":["trace[150030719] 'process raft request' (duration: 494.84752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:26.813948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:26.477099Z","time spent":"336.749451ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:47:26.814026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:26.318491Z","time spent":"495.485753ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:47:26.839898Z","caller":"traceutil/trace.go:171","msg":"trace[1809984922] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"139.576764ms","start":"2026-07-16T16:47:26.700302Z","end":"2026-07-16T16:47:26.839879Z","steps":["trace[1809984922] 'process raft request' (duration: 139.412081ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:27.168872Z","caller":"traceutil/trace.go:171","msg":"trace[781975985] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"404.387647ms","start":"2026-07-16T16:47:26.764419Z","end":"2026-07-16T16:47:27.168806Z","steps":["trace[781975985] 'process raft request' (duration: 358.746112ms)","trace[781975985] 'compare' (duration: 45.154295ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:27.16893Z","caller":"traceutil/trace.go:171","msg":"trace[1890977818] linearizableReadLoop","detail":"{readStateIndex:27514; appliedIndex:27512; }","duration":"354.101964ms","start":"2026-07-16T16:47:26.8148Z","end":"2026-07-16T16:47:27.168902Z","steps":["trace[1890977818] 'read index received' (duration: 25.002729ms)","trace[1890977818] 'applied index is now lower than readState.Index' (duration: 329.098215ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:27.168985Z","caller":"traceutil/trace.go:171","msg":"trace[1836233983] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"189.241826ms","start":"2026-07-16T16:47:26.979715Z","end":"2026-07-16T16:47:27.168957Z","steps":["trace[1836233983] 'process raft request' (duration: 189.000231ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:27.16903Z","caller":"traceutil/trace.go:171","msg":"trace[1012757364] transaction","detail":"{read_only:false; response_revision:26635; number_of_response:1; }","duration":"146.007468ms","start":"2026-07-16T16:47:27.022988Z","end":"2026-07-16T16:47:27.168995Z","steps":["trace[1012757364] 'process raft request' (duration: 145.863335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:27.169097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:26.764394Z","time spent":"404.583391ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:47:27.16923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.411879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:47:27.16927Z","caller":"traceutil/trace.go:171","msg":"trace[736007863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26636; }","duration":"354.503991ms","start":"2026-07-16T16:47:26.814754Z","end":"2026-07-16T16:47:27.169258Z","steps":["trace[736007863] 'agreement among raft nodes before linearized reading' (duration: 354.273727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:27.169301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:26.814735Z","time spent":"354.557952ms","remote":"127.0.0.1:45768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T16:47:27.501089Z","caller":"traceutil/trace.go:171","msg":"trace[254733183] transaction","detail":"{read_only:false; response_revision:26638; number_of_response:1; }","duration":"325.583698ms","start":"2026-07-16T16:47:27.175464Z","end":"2026-07-16T16:47:27.501048Z","steps":["trace[254733183] 'process raft request' (duration: 325.356864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:27.501323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:27.175441Z","time spent":"325.759772ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:47:27.7976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.723726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T16:47:27.797755Z","caller":"traceutil/trace.go:171","msg":"trace[24069875] linearizableReadLoop","detail":"{readStateIndex:27520; appliedIndex:27518; }","duration":"363.703078ms","start":"2026-07-16T16:47:27.434029Z","end":"2026-07-16T16:47:27.797732Z","steps":["trace[24069875] 'read index received' (duration: 66.772809ms)","trace[24069875] 'applied index is now lower than readState.Index' (duration: 296.928709ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:27.797872Z","caller":"traceutil/trace.go:171","msg":"trace[701118375] transaction","detail":"{read_only:false; response_revision:26639; number_of_response:1; }","duration":"622.294642ms","start":"2026-07-16T16:47:27.175508Z","end":"2026-07-16T16:47:27.797803Z","steps":["trace[701118375] 'process raft request' (duration: 481.208619ms)","trace[701118375] 'compare' (duration: 140.240636ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:47:27.798024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.000523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T16:47:27.798068Z","caller":"traceutil/trace.go:171","msg":"trace[1702727523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26639; }","duration":"364.090465ms","start":"2026-07-16T16:47:27.433965Z","end":"2026-07-16T16:47:27.798055Z","steps":["trace[1702727523] 'agreement among raft nodes before linearized reading' (duration: 363.862021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:27.798109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:27.433941Z","time spent":"364.158247ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T16:47:27.798162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:27.17548Z","time spent":"622.484606ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:47:27.820643Z","caller":"traceutil/trace.go:171","msg":"trace[1565213561] transaction","detail":"{read_only:false; response_revision:26640; number_of_response:1; }","duration":"314.948383ms","start":"2026-07-16T16:47:27.505646Z","end":"2026-07-16T16:47:27.820594Z","steps":["trace[1565213561] 'process raft request' (duration: 314.644888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:27.820873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:27.505625Z","time spent":"315.130567ms","remote":"127.0.0.1:46040","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-07-16T16:47:27.821022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.463212ms","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-07-16T16:47:27.821084Z","caller":"traceutil/trace.go:171","msg":"trace[1820120715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26640; }","duration":"281.570784ms","start":"2026-07-16T16:47:27.539495Z","end":"2026-07-16T16:47:27.821065Z","steps":["trace[1820120715] 'agreement among raft nodes before linearized reading' (duration: 280.943132ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:28.084494Z","caller":"traceutil/trace.go:171","msg":"trace[1681936043] transaction","detail":"{read_only:false; response_revision:26641; number_of_response:1; }","duration":"281.883701ms","start":"2026-07-16T16:47:27.802583Z","end":"2026-07-16T16:47:28.084466Z","steps":["trace[1681936043] 'process raft request' (duration: 281.693427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:28.541407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.371926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T16:47:28.541807Z","caller":"traceutil/trace.go:171","msg":"trace[1017799222] linearizableReadLoop","detail":"{readStateIndex:27524; appliedIndex:27521; }","duration":"528.308981ms","start":"2026-07-16T16:47:28.013482Z","end":"2026-07-16T16:47:28.541791Z","steps":["trace[1017799222] 'read index received' (duration: 70.894409ms)","trace[1017799222] 'applied index is now lower than readState.Index' (duration: 457.413392ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:28.541852Z","caller":"traceutil/trace.go:171","msg":"trace[505008676] transaction","detail":"{read_only:false; response_revision:26642; number_of_response:1; }","duration":"715.220842ms","start":"2026-07-16T16:47:27.826586Z","end":"2026-07-16T16:47:28.541807Z","steps":["trace[505008676] 'process raft request' (duration: 404.364957ms)","trace[505008676] 'compare' (duration: 310.237324ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:28.541854Z","caller":"traceutil/trace.go:171","msg":"trace[931196277] transaction","detail":"{read_only:false; response_revision:26643; number_of_response:1; }","duration":"714.453358ms","start":"2026-07-16T16:47:27.82737Z","end":"2026-07-16T16:47:28.541824Z","steps":["trace[931196277] 'process raft request' (duration: 714.348756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:28.54193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.092301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:47:28.541963Z","caller":"traceutil/trace.go:171","msg":"trace[921285119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26643; }","duration":"238.179613ms","start":"2026-07-16T16:47:28.303774Z","end":"2026-07-16T16:47:28.541953Z","steps":["trace[921285119] 'agreement among raft nodes before linearized reading' (duration: 238.114232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:28.541966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.463984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:47:28.542047Z","caller":"traceutil/trace.go:171","msg":"trace[1480572059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26643; }","duration":"528.578436ms","start":"2026-07-16T16:47:28.013446Z","end":"2026-07-16T16:47:28.542024Z","steps":["trace[1480572059] 'agreement among raft nodes before linearized reading' (duration: 528.429004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:28.542061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:27.82657Z","time spent":"715.375235ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:47:28.54208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:28.013426Z","time spent":"528.647377ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T16:47:28.542064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:27.827345Z","time spent":"714.60414ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:47:28.890716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.29176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T16:47:28.890815Z","caller":"traceutil/trace.go:171","msg":"trace[679692490] transaction","detail":"{read_only:false; response_revision:26644; number_of_response:1; }","duration":"344.00503ms","start":"2026-07-16T16:47:28.546793Z","end":"2026-07-16T16:47:28.890798Z","steps":["trace[679692490] 'process raft request' (duration: 124.517276ms)","trace[679692490] 'compare' (duration: 219.104027ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:47:28.890882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:28.546775Z","time spent":"344.070732ms","remote":"127.0.0.1:45788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:47:28.953541Z","caller":"traceutil/trace.go:171","msg":"trace[806270759] transaction","detail":"{read_only:false; response_revision:26646; number_of_response:1; }","duration":"132.399195ms","start":"2026-07-16T16:47:28.821049Z","end":"2026-07-16T16:47:28.953448Z","steps":["trace[806270759] 'process raft request' (duration: 132.12429ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:28.953677Z","caller":"traceutil/trace.go:171","msg":"trace[1119335622] transaction","detail":"{read_only:false; response_revision:26647; number_of_response:1; }","duration":"106.923508ms","start":"2026-07-16T16:47:28.846714Z","end":"2026-07-16T16:47:28.953637Z","steps":["trace[1119335622] 'process raft request' (duration: 106.655443ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:29.255626Z","caller":"traceutil/trace.go:171","msg":"trace[1357629256] transaction","detail":"{read_only:false; response_revision:26649; number_of_response:1; }","duration":"210.542723ms","start":"2026-07-16T16:47:29.045057Z","end":"2026-07-16T16:47:29.255599Z","steps":["trace[1357629256] 'process raft request' (duration: 118.312796ms)","trace[1357629256] 'compare' (duration: 91.884111ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:29.350362Z","caller":"traceutil/trace.go:171","msg":"trace[241387781] transaction","detail":"{read_only:false; response_revision:26651; number_of_response:1; }","duration":"173.642607ms","start":"2026-07-16T16:47:29.176683Z","end":"2026-07-16T16:47:29.350325Z","steps":["trace[241387781] 'process raft request' (duration: 173.378411ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:29.350734Z","caller":"traceutil/trace.go:171","msg":"trace[1514566326] transaction","detail":"{read_only:false; response_revision:26652; number_of_response:1; }","duration":"173.955662ms","start":"2026-07-16T16:47:29.176745Z","end":"2026-07-16T16:47:29.350701Z","steps":["trace[1514566326] 'process raft request' (duration: 173.515014ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:29.350786Z","caller":"traceutil/trace.go:171","msg":"trace[408395005] transaction","detail":"{read_only:false; response_revision:26653; number_of_response:1; }","duration":"173.684408ms","start":"2026-07-16T16:47:29.177076Z","end":"2026-07-16T16:47:29.350761Z","steps":["trace[408395005] 'process raft request' (duration: 173.502164ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:29.677816Z","caller":"traceutil/trace.go:171","msg":"trace[2062254219] transaction","detail":"{read_only:false; response_revision:26654; number_of_response:1; }","duration":"166.026411ms","start":"2026-07-16T16:47:29.511763Z","end":"2026-07-16T16:47:29.677789Z","steps":["trace[2062254219] 'process raft request' (duration: 165.728225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:30.513205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.584816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:47:30.513295Z","caller":"traceutil/trace.go:171","msg":"trace[17146289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26657; }","duration":"207.724819ms","start":"2026-07-16T16:47:30.305549Z","end":"2026-07-16T16:47:30.513274Z","steps":["trace[17146289] 'range keys from in-memory index tree' (duration: 207.501935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:38.269817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.812324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T16:47:38.269967Z","caller":"traceutil/trace.go:171","msg":"trace[1537464501] linearizableReadLoop","detail":"{readStateIndex:27607; appliedIndex:27606; }","duration":"204.64383ms","start":"2026-07-16T16:47:38.065289Z","end":"2026-07-16T16:47:38.269933Z","steps":["trace[1537464501] 'read index received' (duration: 70.580422ms)","trace[1537464501] 'applied index is now lower than readState.Index' (duration: 134.061448ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:38.270083Z","caller":"traceutil/trace.go:171","msg":"trace[287242782] transaction","detail":"{read_only:false; response_revision:26724; number_of_response:1; }","duration":"269.422401ms","start":"2026-07-16T16:47:38.000649Z","end":"2026-07-16T16:47:38.270072Z","steps":["trace[287242782] 'process raft request' (duration: 135.24484ms)","trace[287242782] 'compare' (duration: 133.551489ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:47:38.270372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.065279ms","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-07-16T16:47:38.270424Z","caller":"traceutil/trace.go:171","msg":"trace[773653985] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26724; }","duration":"205.194391ms","start":"2026-07-16T16:47:38.065217Z","end":"2026-07-16T16:47:38.270411Z","steps":["trace[773653985] 'agreement among raft nodes before linearized reading' (duration: 205.070638ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:39.081358Z","caller":"traceutil/trace.go:171","msg":"trace[518360386] transaction","detail":"{read_only:false; response_revision:26729; number_of_response:1; }","duration":"152.266118ms","start":"2026-07-16T16:47:38.929063Z","end":"2026-07-16T16:47:39.08133Z","steps":["trace[518360386] 'process raft request' (duration: 152.060854ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:39.221627Z","caller":"traceutil/trace.go:171","msg":"trace[1291918644] transaction","detail":"{read_only:false; response_revision:26730; number_of_response:1; }","duration":"219.524616ms","start":"2026-07-16T16:47:39.001987Z","end":"2026-07-16T16:47:39.221512Z","steps":["trace[1291918644] 'process raft request' (duration: 215.146432ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:39.221706Z","caller":"traceutil/trace.go:171","msg":"trace[1014934725] transaction","detail":"{read_only:false; response_revision:26731; number_of_response:1; }","duration":"219.279981ms","start":"2026-07-16T16:47:39.002403Z","end":"2026-07-16T16:47:39.221683Z","steps":["trace[1014934725] 'process raft request' (duration: 218.962125ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:39.659523Z","caller":"traceutil/trace.go:171","msg":"trace[798585338] linearizableReadLoop","detail":"{readStateIndex:27616; appliedIndex:27615; }","duration":"354.204246ms","start":"2026-07-16T16:47:39.305295Z","end":"2026-07-16T16:47:39.659499Z","steps":["trace[798585338] 'read index received' (duration: 353.92746ms)","trace[798585338] 'applied index is now lower than readState.Index' (duration: 275.656µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:39.659626Z","caller":"traceutil/trace.go:171","msg":"trace[842284353] transaction","detail":"{read_only:false; response_revision:26732; number_of_response:1; }","duration":"363.897762ms","start":"2026-07-16T16:47:39.295675Z","end":"2026-07-16T16:47:39.659573Z","steps":["trace[842284353] 'process raft request' (duration: 363.473743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:39.659678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.373549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:47:39.659871Z","caller":"traceutil/trace.go:171","msg":"trace[1027218980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26732; }","duration":"354.626354ms","start":"2026-07-16T16:47:39.305228Z","end":"2026-07-16T16:47:39.659854Z","steps":["trace[1027218980] 'agreement among raft nodes before linearized reading' (duration: 354.394619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:39.659876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:39.295643Z","time spent":"364.101966ms","remote":"127.0.0.1:46040","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-07-16T16:47:39.659913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:39.305188Z","time spent":"354.715786ms","remote":"127.0.0.1:45758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T16:47:39.680979Z","caller":"traceutil/trace.go:171","msg":"trace[1930887650] transaction","detail":"{read_only:false; response_revision:26734; number_of_response:1; }","duration":"282.240778ms","start":"2026-07-16T16:47:39.398705Z","end":"2026-07-16T16:47:39.680945Z","steps":["trace[1930887650] 'process raft request' (duration: 282.138026ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:39.680984Z","caller":"traceutil/trace.go:171","msg":"trace[1510071966] transaction","detail":"{read_only:false; response_revision:26733; number_of_response:1; }","duration":"289.814293ms","start":"2026-07-16T16:47:39.391131Z","end":"2026-07-16T16:47:39.680945Z","steps":["trace[1510071966] 'process raft request' (duration: 289.526827ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:39.681434Z","caller":"traceutil/trace.go:171","msg":"trace[918204010] transaction","detail":"{read_only:false; response_revision:26735; number_of_response:1; }","duration":"279.358981ms","start":"2026-07-16T16:47:39.402058Z","end":"2026-07-16T16:47:39.681417Z","steps":["trace[918204010] 'process raft request' (duration: 278.838802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:40.037325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.58994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T16:47:40.037395Z","caller":"traceutil/trace.go:171","msg":"trace[1672446576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26735; }","duration":"228.716663ms","start":"2026-07-16T16:47:39.808662Z","end":"2026-07-16T16:47:40.037379Z","steps":["trace[1672446576] 'range keys from in-memory index tree' (duration: 228.361835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:40.037335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.807228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:47:40.037454Z","caller":"traceutil/trace.go:171","msg":"trace[633355069] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26735; }","duration":"294.969722ms","start":"2026-07-16T16:47:39.742459Z","end":"2026-07-16T16:47:40.037428Z","steps":["trace[633355069] 'range keys from in-memory index tree' (duration: 294.705175ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:40.22696Z","caller":"traceutil/trace.go:171","msg":"trace[986617286] linearizableReadLoop","detail":"{readStateIndex:27621; appliedIndex:27620; }","duration":"172.089247ms","start":"2026-07-16T16:47:40.054844Z","end":"2026-07-16T16:47:40.226933Z","steps":["trace[986617286] 'read index received' (duration: 171.842152ms)","trace[986617286] 'applied index is now lower than readState.Index' (duration: 246.155µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:40.227089Z","caller":"traceutil/trace.go:171","msg":"trace[644470889] transaction","detail":"{read_only:false; response_revision:26737; number_of_response:1; }","duration":"185.767749ms","start":"2026-07-16T16:47:40.041278Z","end":"2026-07-16T16:47:40.227046Z","steps":["trace[644470889] 'process raft request' (duration: 185.473863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:40.227183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.322651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-16T16:47:40.227218Z","caller":"traceutil/trace.go:171","msg":"trace[1660797505] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26737; }","duration":"172.437724ms","start":"2026-07-16T16:47:40.05477Z","end":"2026-07-16T16:47:40.227208Z","steps":["trace[1660797505] 'agreement among raft nodes before linearized reading' (duration: 172.264181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:40.581279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.070298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T16:47:40.581436Z","caller":"traceutil/trace.go:171","msg":"trace[840732411] linearizableReadLoop","detail":"{readStateIndex:27623; appliedIndex:27621; }","duration":"283.531181ms","start":"2026-07-16T16:47:40.29789Z","end":"2026-07-16T16:47:40.581422Z","steps":["trace[840732411] 'read index received' (duration: 125.245238ms)","trace[840732411] 'applied index is now lower than readState.Index' (duration: 158.285053ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:40.581516Z","caller":"traceutil/trace.go:171","msg":"trace[874208632] transaction","detail":"{read_only:false; response_revision:26739; number_of_response:1; }","duration":"295.738055ms","start":"2026-07-16T16:47:40.28577Z","end":"2026-07-16T16:47:40.581509Z","steps":["trace[874208632] 'process raft request' (duration: 295.603383ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:40.58159Z","caller":"traceutil/trace.go:171","msg":"trace[1557050258] transaction","detail":"{read_only:false; response_revision:26738; number_of_response:1; }","duration":"301.698359ms","start":"2026-07-16T16:47:40.279856Z","end":"2026-07-16T16:47:40.581555Z","steps":["trace[1557050258] 'process raft request' (duration: 143.264644ms)","trace[1557050258] 'compare' (duration: 157.950476ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:47:40.581769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:40.279842Z","time spent":"301.823081ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:47:40.58181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.921579ms","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-07-16T16:47:40.581838Z","caller":"traceutil/trace.go:171","msg":"trace[1603483670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26739; }","duration":"283.949949ms","start":"2026-07-16T16:47:40.29788Z","end":"2026-07-16T16:47:40.58183Z","steps":["trace[1603483670] 'agreement among raft nodes before linearized reading' (duration: 283.847648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:40.581934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.839863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:47:40.58195Z","caller":"traceutil/trace.go:171","msg":"trace[982364633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26739; }","duration":"276.867975ms","start":"2026-07-16T16:47:40.305077Z","end":"2026-07-16T16:47:40.581945Z","steps":["trace[982364633] 'agreement among raft nodes before linearized reading' (duration: 276.836343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:40.582072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.965064ms","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-07-16T16:47:40.58214Z","caller":"traceutil/trace.go:171","msg":"trace[396107046] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26739; }","duration":"244.073577ms","start":"2026-07-16T16:47:40.33805Z","end":"2026-07-16T16:47:40.582124Z","steps":["trace[396107046] 'agreement among raft nodes before linearized reading' (duration: 243.933034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:41.008738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.076039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T16:47:41.008848Z","caller":"traceutil/trace.go:171","msg":"trace[614214310] linearizableReadLoop","detail":"{readStateIndex:27624; appliedIndex:27623; }","duration":"305.152016ms","start":"2026-07-16T16:47:40.703679Z","end":"2026-07-16T16:47:41.008831Z","steps":["trace[614214310] 'read index received' (duration: 41.13µs)","trace[614214310] 'applied index is now lower than readState.Index' (duration: 305.109446ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:41.009003Z","caller":"traceutil/trace.go:171","msg":"trace[1093068523] transaction","detail":"{read_only:false; response_revision:26740; number_of_response:1; }","duration":"422.179489ms","start":"2026-07-16T16:47:40.586807Z","end":"2026-07-16T16:47:41.008986Z","steps":["trace[1093068523] 'process raft request' (duration: 109.759293ms)","trace[1093068523] 'compare' (duration: 311.748342ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:47:41.009068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:40.586784Z","time spent":"422.25256ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:47:41.00917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.331696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-16T16:47:41.009253Z","caller":"traceutil/trace.go:171","msg":"trace[688413331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26740; }","duration":"286.451178ms","start":"2026-07-16T16:47:40.72278Z","end":"2026-07-16T16:47:41.009231Z","steps":["trace[688413331] 'agreement among raft nodes before linearized reading' (duration: 286.243924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:41.009239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.524224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:47:41.009352Z","caller":"traceutil/trace.go:171","msg":"trace[1921303307] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:26740; }","duration":"305.658757ms","start":"2026-07-16T16:47:40.703663Z","end":"2026-07-16T16:47:41.009322Z","steps":["trace[1921303307] 'agreement among raft nodes before linearized reading' (duration: 305.359521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:41.009413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:40.70364Z","time spent":"305.759468ms","remote":"127.0.0.1:47438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true "} {"level":"warn","ts":"2026-07-16T16:47:41.009402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.392187ms","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-07-16T16:47:41.00964Z","caller":"traceutil/trace.go:171","msg":"trace[1869410478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26740; }","duration":"285.648092ms","start":"2026-07-16T16:47:40.723905Z","end":"2026-07-16T16:47:41.009553Z","steps":["trace[1869410478] 'agreement among raft nodes before linearized reading' (duration: 285.243885ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:41.473693Z","caller":"traceutil/trace.go:171","msg":"trace[895635603] transaction","detail":"{read_only:false; response_revision:26743; number_of_response:1; }","duration":"457.967693ms","start":"2026-07-16T16:47:41.015696Z","end":"2026-07-16T16:47:41.473664Z","steps":["trace[895635603] 'process raft request' (duration: 457.80719ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:41.473788Z","caller":"traceutil/trace.go:171","msg":"trace[1487016082] transaction","detail":"{read_only:false; response_revision:26742; number_of_response:1; }","duration":"459.560254ms","start":"2026-07-16T16:47:41.014192Z","end":"2026-07-16T16:47:41.473752Z","steps":["trace[1487016082] 'process raft request' (duration: 439.554451ms)","trace[1487016082] 'compare' (duration: 19.140297ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:47:41.473898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:41.015672Z","time spent":"458.112557ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:47:41.474031Z","caller":"traceutil/trace.go:171","msg":"trace[1036129515] transaction","detail":"{read_only:false; response_revision:26744; number_of_response:1; }","duration":"386.653099ms","start":"2026-07-16T16:47:41.087349Z","end":"2026-07-16T16:47:41.474002Z","steps":["trace[1036129515] 'process raft request' (duration: 386.515256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:41.473948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:41.014159Z","time spent":"459.708328ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:47:41.474176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:41.087319Z","time spent":"386.78158ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:47:41.474193Z","caller":"traceutil/trace.go:171","msg":"trace[1287941268] transaction","detail":"{read_only:false; response_revision:26745; number_of_response:1; }","duration":"245.150887ms","start":"2026-07-16T16:47:41.229034Z","end":"2026-07-16T16:47:41.474184Z","steps":["trace[1287941268] 'process raft request' (duration: 244.933173ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:41.47436Z","caller":"traceutil/trace.go:171","msg":"trace[1143453722] linearizableReadLoop","detail":"{readStateIndex:27630; appliedIndex:27625; }","duration":"228.913516ms","start":"2026-07-16T16:47:41.245436Z","end":"2026-07-16T16:47:41.47435Z","steps":["trace[1143453722] 'read index received' (duration: 208.407503ms)","trace[1143453722] 'applied index is now lower than readState.Index' (duration: 20.505073ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:41.474407Z","caller":"traceutil/trace.go:171","msg":"trace[9177479] transaction","detail":"{read_only:false; response_revision:26746; number_of_response:1; }","duration":"245.152597ms","start":"2026-07-16T16:47:41.229236Z","end":"2026-07-16T16:47:41.474389Z","steps":["trace[9177479] 'process raft request' (duration: 244.881022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:41.474484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.046858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T16:47:41.474534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.837214ms","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:8"} {"level":"info","ts":"2026-07-16T16:47:41.474539Z","caller":"traceutil/trace.go:171","msg":"trace[1847806355] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26746; }","duration":"229.13745ms","start":"2026-07-16T16:47:41.245389Z","end":"2026-07-16T16:47:41.474526Z","steps":["trace[1847806355] 'agreement among raft nodes before linearized reading' (duration: 229.045068ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:41.474568Z","caller":"traceutil/trace.go:171","msg":"trace[2008525167] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26746; }","duration":"180.905156ms","start":"2026-07-16T16:47:41.293653Z","end":"2026-07-16T16:47:41.474558Z","steps":["trace[2008525167] 'agreement among raft nodes before linearized reading' (duration: 180.838005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:41.474809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.499585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:47:41.47484Z","caller":"traceutil/trace.go:171","msg":"trace[1942896654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26746; }","duration":"171.558186ms","start":"2026-07-16T16:47:41.303275Z","end":"2026-07-16T16:47:41.474833Z","steps":["trace[1942896654] 'agreement among raft nodes before linearized reading' (duration: 171.446604ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:43.334914Z","caller":"traceutil/trace.go:171","msg":"trace[1907795462] linearizableReadLoop","detail":"{readStateIndex:27644; appliedIndex:27643; }","duration":"307.191056ms","start":"2026-07-16T16:47:43.027695Z","end":"2026-07-16T16:47:43.334886Z","steps":["trace[1907795462] 'read index received' (duration: 307.000493ms)","trace[1907795462] 'applied index is now lower than readState.Index' (duration: 189.133µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:43.335026Z","caller":"traceutil/trace.go:171","msg":"trace[40263943] transaction","detail":"{read_only:false; response_revision:26760; number_of_response:1; }","duration":"316.933372ms","start":"2026-07-16T16:47:43.018053Z","end":"2026-07-16T16:47:43.334986Z","steps":["trace[40263943] 'process raft request' (duration: 316.670017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:43.335385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.876333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-conductor-0.18c2d308a07a8012\" ","response":"range_response_count:1 size:869"} {"level":"warn","ts":"2026-07-16T16:47:43.335388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.672915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-16T16:47:43.335408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.462814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-16T16:47:43.335444Z","caller":"traceutil/trace.go:171","msg":"trace[1179157915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26760; }","duration":"307.761096ms","start":"2026-07-16T16:47:43.027668Z","end":"2026-07-16T16:47:43.335429Z","steps":["trace[1179157915] 'agreement among raft nodes before linearized reading' (duration: 307.354739ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:43.335442Z","caller":"traceutil/trace.go:171","msg":"trace[815747819] range","detail":"{range_begin:/registry/events/openstack/magnum-conductor-0.18c2d308a07a8012; range_end:; response_count:1; response_revision:26760; }","duration":"230.986195ms","start":"2026-07-16T16:47:43.104436Z","end":"2026-07-16T16:47:43.335422Z","steps":["trace[815747819] 'agreement among raft nodes before linearized reading' (duration: 230.753971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:43.335441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:43.018016Z","time spent":"317.32895ms","remote":"127.0.0.1:46040","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-07-16T16:47:43.335473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:43.027656Z","time spent":"307.809607ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-16T16:47:43.335468Z","caller":"traceutil/trace.go:171","msg":"trace[36997909] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:26760; }","duration":"184.569557ms","start":"2026-07-16T16:47:43.150882Z","end":"2026-07-16T16:47:43.335451Z","steps":["trace[36997909] 'agreement among raft nodes before linearized reading' (duration: 184.265111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:43.33584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.043064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-conductor-0\" ","response":"range_response_count:1 size:10438"} {"level":"info","ts":"2026-07-16T16:47:43.33588Z","caller":"traceutil/trace.go:171","msg":"trace[701653211] range","detail":"{range_begin:/registry/pods/openstack/magnum-conductor-0; range_end:; response_count:1; response_revision:26760; }","duration":"233.131016ms","start":"2026-07-16T16:47:43.102737Z","end":"2026-07-16T16:47:43.335868Z","steps":["trace[701653211] 'agreement among raft nodes before linearized reading' (duration: 232.315801ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:43.462794Z","caller":"traceutil/trace.go:171","msg":"trace[1143702585] transaction","detail":"{read_only:false; response_revision:26761; number_of_response:1; }","duration":"124.631608ms","start":"2026-07-16T16:47:43.338134Z","end":"2026-07-16T16:47:43.462765Z","steps":["trace[1143702585] 'process raft request' (duration: 122.16489ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:43.468642Z","caller":"traceutil/trace.go:171","msg":"trace[924088657] transaction","detail":"{read_only:false; response_revision:26762; number_of_response:1; }","duration":"128.203165ms","start":"2026-07-16T16:47:43.340412Z","end":"2026-07-16T16:47:43.468615Z","steps":["trace[924088657] 'process raft request' (duration: 127.572674ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:43.46882Z","caller":"traceutil/trace.go:171","msg":"trace[920598072] transaction","detail":"{read_only:false; response_revision:26763; number_of_response:1; }","duration":"122.833643ms","start":"2026-07-16T16:47:43.345948Z","end":"2026-07-16T16:47:43.468782Z","steps":["trace[920598072] 'process raft request' (duration: 122.260632ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:47.831155Z","caller":"traceutil/trace.go:171","msg":"trace[1021722786] transaction","detail":"{read_only:false; response_revision:26805; number_of_response:1; }","duration":"142.539561ms","start":"2026-07-16T16:47:47.688594Z","end":"2026-07-16T16:47:47.831134Z","steps":["trace[1021722786] 'process raft request' (duration: 142.422429ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:47.964735Z","caller":"traceutil/trace.go:171","msg":"trace[590373796] transaction","detail":"{read_only:false; response_revision:26808; number_of_response:1; }","duration":"251.952537ms","start":"2026-07-16T16:47:47.712755Z","end":"2026-07-16T16:47:47.964708Z","steps":["trace[590373796] 'process raft request' (duration: 251.903187ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:47.965156Z","caller":"traceutil/trace.go:171","msg":"trace[1137063555] transaction","detail":"{read_only:false; response_revision:26806; number_of_response:1; }","duration":"252.616991ms","start":"2026-07-16T16:47:47.712528Z","end":"2026-07-16T16:47:47.965145Z","steps":["trace[1137063555] 'process raft request' (duration: 251.967998ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:47.965283Z","caller":"traceutil/trace.go:171","msg":"trace[431966110] transaction","detail":"{read_only:false; response_revision:26807; number_of_response:1; }","duration":"252.655571ms","start":"2026-07-16T16:47:47.712619Z","end":"2026-07-16T16:47:47.965274Z","steps":["trace[431966110] 'process raft request' (duration: 251.999628ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:48.2457Z","caller":"traceutil/trace.go:171","msg":"trace[703726873] transaction","detail":"{read_only:false; response_revision:26809; number_of_response:1; }","duration":"143.928857ms","start":"2026-07-16T16:47:48.10175Z","end":"2026-07-16T16:47:48.245679Z","steps":["trace[703726873] 'process raft request' (duration: 143.794265ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:48.399821Z","caller":"traceutil/trace.go:171","msg":"trace[1167572732] transaction","detail":"{read_only:false; response_revision:26810; number_of_response:1; }","duration":"138.577555ms","start":"2026-07-16T16:47:48.261217Z","end":"2026-07-16T16:47:48.399795Z","steps":["trace[1167572732] 'process raft request' (duration: 136.160868ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:50.179426Z","caller":"traceutil/trace.go:171","msg":"trace[1227938961] transaction","detail":"{read_only:false; response_revision:26881; number_of_response:1; }","duration":"209.082026ms","start":"2026-07-16T16:47:49.970316Z","end":"2026-07-16T16:47:50.179398Z","steps":["trace[1227938961] 'process raft request' (duration: 136.043536ms)","trace[1227938961] 'compare' (duration: 72.735664ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:50.179465Z","caller":"traceutil/trace.go:171","msg":"trace[1360970971] transaction","detail":"{read_only:false; response_revision:26883; number_of_response:1; }","duration":"208.323781ms","start":"2026-07-16T16:47:49.971114Z","end":"2026-07-16T16:47:50.179438Z","steps":["trace[1360970971] 'process raft request' (duration: 208.27451ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:50.179622Z","caller":"traceutil/trace.go:171","msg":"trace[6139982] transaction","detail":"{read_only:false; response_revision:26882; number_of_response:1; }","duration":"209.062465ms","start":"2026-07-16T16:47:49.970553Z","end":"2026-07-16T16:47:50.179615Z","steps":["trace[6139982] 'process raft request' (duration: 208.777359ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:51.665501Z","caller":"traceutil/trace.go:171","msg":"trace[2017553806] linearizableReadLoop","detail":"{readStateIndex:27778; appliedIndex:27777; }","duration":"147.505878ms","start":"2026-07-16T16:47:51.517967Z","end":"2026-07-16T16:47:51.665473Z","steps":["trace[2017553806] 'read index received' (duration: 147.308754ms)","trace[2017553806] 'applied index is now lower than readState.Index' (duration: 195.614µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:51.665659Z","caller":"traceutil/trace.go:171","msg":"trace[1591171068] transaction","detail":"{read_only:false; response_revision:26891; number_of_response:1; }","duration":"147.715112ms","start":"2026-07-16T16:47:51.517894Z","end":"2026-07-16T16:47:51.665609Z","steps":["trace[1591171068] 'process raft request' (duration: 147.398045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:51.665873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.907515ms","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-07-16T16:47:51.665972Z","caller":"traceutil/trace.go:171","msg":"trace[1587084611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26891; }","duration":"148.116739ms","start":"2026-07-16T16:47:51.517833Z","end":"2026-07-16T16:47:51.665949Z","steps":["trace[1587084611] 'agreement among raft nodes before linearized reading' (duration: 147.811033ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:51.677711Z","caller":"traceutil/trace.go:171","msg":"trace[132933724] transaction","detail":"{read_only:false; response_revision:26892; number_of_response:1; }","duration":"159.632375ms","start":"2026-07-16T16:47:51.518023Z","end":"2026-07-16T16:47:51.677655Z","steps":["trace[132933724] 'process raft request' (duration: 157.582447ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:51.677731Z","caller":"traceutil/trace.go:171","msg":"trace[1483498430] transaction","detail":"{read_only:false; response_revision:26893; number_of_response:1; }","duration":"158.130176ms","start":"2026-07-16T16:47:51.51958Z","end":"2026-07-16T16:47:51.67771Z","steps":["trace[1483498430] 'process raft request' (duration: 157.986384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:51.677904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.231517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-16T16:47:51.677967Z","caller":"traceutil/trace.go:171","msg":"trace[1082187333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26894; }","duration":"150.33312ms","start":"2026-07-16T16:47:51.527618Z","end":"2026-07-16T16:47:51.677951Z","steps":["trace[1082187333] 'agreement among raft nodes before linearized reading' (duration: 150.119986ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:56.720878Z","caller":"traceutil/trace.go:171","msg":"trace[1005922920] transaction","detail":"{read_only:false; response_revision:26950; number_of_response:1; }","duration":"236.475071ms","start":"2026-07-16T16:47:56.48438Z","end":"2026-07-16T16:47:56.720855Z","steps":["trace[1005922920] 'process raft request' (duration: 236.313519ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:56.903281Z","caller":"traceutil/trace.go:171","msg":"trace[751829797] linearizableReadLoop","detail":"{readStateIndex:27840; appliedIndex:27838; }","duration":"339.022802ms","start":"2026-07-16T16:47:56.564225Z","end":"2026-07-16T16:47:56.903247Z","steps":["trace[751829797] 'read index received' (duration: 156.438315ms)","trace[751829797] 'applied index is now lower than readState.Index' (duration: 182.582887ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:56.903366Z","caller":"traceutil/trace.go:171","msg":"trace[425155875] transaction","detail":"{read_only:false; response_revision:26951; number_of_response:1; }","duration":"416.853398ms","start":"2026-07-16T16:47:56.486462Z","end":"2026-07-16T16:47:56.903316Z","steps":["trace[425155875] 'process raft request' (duration: 327.681089ms)","trace[425155875] 'compare' (duration: 88.874993ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:47:56.903549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:56.486423Z","time spent":"417.025911ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:47:56.903548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.308858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-16T16:47:56.903638Z","caller":"traceutil/trace.go:171","msg":"trace[1528322743] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26951; }","duration":"339.450581ms","start":"2026-07-16T16:47:56.564165Z","end":"2026-07-16T16:47:56.903615Z","steps":["trace[1528322743] 'agreement among raft nodes before linearized reading' (duration: 339.215076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:56.903696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:56.564141Z","time spent":"339.543463ms","remote":"127.0.0.1:45912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-07-16T16:47:57.260595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.689632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T16:47:57.260956Z","caller":"traceutil/trace.go:171","msg":"trace[1288703982] transaction","detail":"{read_only:false; response_revision:26955; number_of_response:1; }","duration":"531.805643ms","start":"2026-07-16T16:47:56.729133Z","end":"2026-07-16T16:47:57.260939Z","steps":["trace[1288703982] 'process raft request' (duration: 531.742071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:57.261038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:56.729115Z","time spent":"531.887534ms","remote":"127.0.0.1:46048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:47:57.261017Z","caller":"traceutil/trace.go:171","msg":"trace[1684808449] transaction","detail":"{read_only:false; response_revision:26953; number_of_response:1; }","duration":"532.162878ms","start":"2026-07-16T16:47:56.728814Z","end":"2026-07-16T16:47:57.260977Z","steps":["trace[1684808449] 'process raft request' (duration: 531.909054ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:47:57.261098Z","caller":"traceutil/trace.go:171","msg":"trace[786102668] transaction","detail":"{read_only:false; response_revision:26954; number_of_response:1; }","duration":"531.947455ms","start":"2026-07-16T16:47:56.729112Z","end":"2026-07-16T16:47:57.261059Z","steps":["trace[786102668] 'process raft request' (duration: 531.717831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:57.261193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:56.728803Z","time spent":"532.292291ms","remote":"127.0.0.1:45924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:47:57.261294Z","caller":"traceutil/trace.go:171","msg":"trace[1160512035] transaction","detail":"{read_only:false; response_revision:26952; number_of_response:1; }","duration":"532.834861ms","start":"2026-07-16T16:47:56.72843Z","end":"2026-07-16T16:47:57.261265Z","steps":["trace[1160512035] 'process raft request' (duration: 291.386996ms)","trace[1160512035] 'compare' (duration: 240.529318ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:47:57.261309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:56.729095Z","time spent":"532.089857ms","remote":"127.0.0.1:45924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:47:57.261426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:56.7284Z","time spent":"532.964873ms","remote":"127.0.0.1:46048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:47:57.262096Z","caller":"traceutil/trace.go:171","msg":"trace[729963257] linearizableReadLoop","detail":"{readStateIndex:27845; appliedIndex:27840; }","duration":"358.704073ms","start":"2026-07-16T16:47:56.903373Z","end":"2026-07-16T16:47:57.262077Z","steps":["trace[729963257] 'read index received' (duration: 116.461063ms)","trace[729963257] 'applied index is now lower than readState.Index' (duration: 242.24166ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:57.262186Z","caller":"traceutil/trace.go:171","msg":"trace[800787560] transaction","detail":"{read_only:false; response_revision:26956; number_of_response:1; }","duration":"435.428138ms","start":"2026-07-16T16:47:56.826726Z","end":"2026-07-16T16:47:57.262154Z","steps":["trace[800787560] 'process raft request' (duration: 435.205664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:57.262298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:56.826702Z","time spent":"435.545479ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:47:57.262306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.37824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-16T16:47:57.262364Z","caller":"traceutil/trace.go:171","msg":"trace[764097671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26956; }","duration":"543.472011ms","start":"2026-07-16T16:47:56.718874Z","end":"2026-07-16T16:47:57.262346Z","steps":["trace[764097671] 'agreement among raft nodes before linearized reading' (duration: 543.307028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:57.262424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:56.718855Z","time spent":"543.557333ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-16T16:47:57.27537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.198371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d3b1f761-8041-4bad-a289-d857f7d37c1d\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-16T16:47:57.275435Z","caller":"traceutil/trace.go:171","msg":"trace[1949343569] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d3b1f761-8041-4bad-a289-d857f7d37c1d; range_end:; response_count:1; response_revision:26957; }","duration":"368.290334ms","start":"2026-07-16T16:47:56.907127Z","end":"2026-07-16T16:47:57.275417Z","steps":["trace[1949343569] 'agreement among raft nodes before linearized reading' (duration: 368.134391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:47:57.27547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:47:56.90711Z","time spent":"368.350954ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-d3b1f761-8041-4bad-a289-d857f7d37c1d\" "} {"level":"warn","ts":"2026-07-16T16:47:57.48243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.35716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T16:47:57.482652Z","caller":"traceutil/trace.go:171","msg":"trace[1290304366] transaction","detail":"{read_only:false; response_revision:26958; number_of_response:1; }","duration":"215.62777ms","start":"2026-07-16T16:47:57.266999Z","end":"2026-07-16T16:47:57.482627Z","steps":["trace[1290304366] 'process raft request' (duration: 97.956734ms)","trace[1290304366] 'compare' (duration: 117.108665ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:47:57.482841Z","caller":"traceutil/trace.go:171","msg":"trace[190185667] transaction","detail":"{read_only:false; response_revision:26959; number_of_response:1; }","duration":"182.473925ms","start":"2026-07-16T16:47:57.300337Z","end":"2026-07-16T16:47:57.482811Z","steps":["trace[190185667] 'process raft request' (duration: 182.20966ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:01.815961Z","caller":"traceutil/trace.go:171","msg":"trace[1126664454] transaction","detail":"{read_only:false; response_revision:26998; number_of_response:1; }","duration":"118.773266ms","start":"2026-07-16T16:48:01.697166Z","end":"2026-07-16T16:48:01.815939Z","steps":["trace[1126664454] 'process raft request' (duration: 118.626103ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:02.000736Z","caller":"traceutil/trace.go:171","msg":"trace[129938165] linearizableReadLoop","detail":"{readStateIndex:27890; appliedIndex:27887; }","duration":"286.701808ms","start":"2026-07-16T16:48:01.714008Z","end":"2026-07-16T16:48:02.00071Z","steps":["trace[129938165] 'read index received' (duration: 101.834327ms)","trace[129938165] 'applied index is now lower than readState.Index' (duration: 184.866571ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:02.000853Z","caller":"traceutil/trace.go:171","msg":"trace[1842385275] transaction","detail":"{read_only:false; response_revision:27000; number_of_response:1; }","duration":"290.873856ms","start":"2026-07-16T16:48:01.709968Z","end":"2026-07-16T16:48:02.000842Z","steps":["trace[1842385275] 'process raft request' (duration: 290.684693ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:02.000829Z","caller":"traceutil/trace.go:171","msg":"trace[1534423502] transaction","detail":"{read_only:false; response_revision:26999; number_of_response:1; }","duration":"291.719662ms","start":"2026-07-16T16:48:01.709067Z","end":"2026-07-16T16:48:02.000787Z","steps":["trace[1534423502] 'process raft request' (duration: 249.770102ms)","trace[1534423502] 'compare' (duration: 41.647635ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:48:02.001073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.036994ms","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-07-16T16:48:02.001169Z","caller":"traceutil/trace.go:171","msg":"trace[615804273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27000; }","duration":"287.176587ms","start":"2026-07-16T16:48:01.71397Z","end":"2026-07-16T16:48:02.001147Z","steps":["trace[615804273] 'agreement among raft nodes before linearized reading' (duration: 286.907672ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:02.085588Z","caller":"traceutil/trace.go:171","msg":"trace[1752589400] transaction","detail":"{read_only:false; response_revision:27001; number_of_response:1; }","duration":"204.335496ms","start":"2026-07-16T16:48:01.88122Z","end":"2026-07-16T16:48:02.085556Z","steps":["trace[1752589400] 'process raft request' (duration: 204.141943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:02.085702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.784455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-16T16:48:02.085769Z","caller":"traceutil/trace.go:171","msg":"trace[1207462364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27001; }","duration":"349.899318ms","start":"2026-07-16T16:48:01.735851Z","end":"2026-07-16T16:48:02.085751Z","steps":["trace[1207462364] 'agreement among raft nodes before linearized reading' (duration: 349.657883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:02.085806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:48:01.735837Z","time spent":"349.962859ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T16:48:02.085915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.580518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T16:48:02.085915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.084117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:48:02.085965Z","caller":"traceutil/trace.go:171","msg":"trace[1369268381] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:27001; }","duration":"156.684899ms","start":"2026-07-16T16:48:01.929266Z","end":"2026-07-16T16:48:02.08595Z","steps":["trace[1369268381] 'agreement among raft nodes before linearized reading' (duration: 156.544457ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:02.085993Z","caller":"traceutil/trace.go:171","msg":"trace[1750130456] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:27001; }","duration":"124.195789ms","start":"2026-07-16T16:48:01.961779Z","end":"2026-07-16T16:48:02.085975Z","steps":["trace[1750130456] 'agreement among raft nodes before linearized reading' (duration: 124.023196ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:02.244915Z","caller":"traceutil/trace.go:171","msg":"trace[712848211] transaction","detail":"{read_only:false; response_revision:27002; number_of_response:1; }","duration":"238.49974ms","start":"2026-07-16T16:48:02.006393Z","end":"2026-07-16T16:48:02.244893Z","steps":["trace[712848211] 'process raft request' (duration: 238.310046ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:02.271453Z","caller":"traceutil/trace.go:171","msg":"trace[1709005005] linearizableReadLoop","detail":"{readStateIndex:27893; appliedIndex:27891; }","duration":"168.618125ms","start":"2026-07-16T16:48:02.102816Z","end":"2026-07-16T16:48:02.271434Z","steps":["trace[1709005005] 'read index received' (duration: 141.887601ms)","trace[1709005005] 'applied index is now lower than readState.Index' (duration: 26.729124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:48:02.271668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.829908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-conductor-0\" ","response":"range_response_count:1 size:10438"} {"level":"warn","ts":"2026-07-16T16:48:02.271696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.347734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-16T16:48:02.271733Z","caller":"traceutil/trace.go:171","msg":"trace[1861861314] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:27006; }","duration":"139.452655ms","start":"2026-07-16T16:48:02.132269Z","end":"2026-07-16T16:48:02.271722Z","steps":["trace[1861861314] 'agreement among raft nodes before linearized reading' (duration: 139.325463ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:02.271731Z","caller":"traceutil/trace.go:171","msg":"trace[1297548393] range","detail":"{range_begin:/registry/pods/openstack/magnum-conductor-0; range_end:; response_count:1; response_revision:27006; }","duration":"168.939991ms","start":"2026-07-16T16:48:02.102776Z","end":"2026-07-16T16:48:02.271716Z","steps":["trace[1297548393] 'agreement among raft nodes before linearized reading' (duration: 168.732607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:02.271694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.797748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-conductor-0.18c2d30bfcf59787\" ","response":"range_response_count:1 size:830"} {"level":"info","ts":"2026-07-16T16:48:02.271853Z","caller":"traceutil/trace.go:171","msg":"trace[2099733132] range","detail":"{range_begin:/registry/events/openstack/magnum-conductor-0.18c2d30bfcf59787; range_end:; response_count:1; response_revision:27006; }","duration":"169.019522ms","start":"2026-07-16T16:48:02.102823Z","end":"2026-07-16T16:48:02.271843Z","steps":["trace[2099733132] 'agreement among raft nodes before linearized reading' (duration: 168.747217ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:02.271339Z","caller":"traceutil/trace.go:171","msg":"trace[148643586] transaction","detail":"{read_only:false; response_revision:27003; number_of_response:1; }","duration":"181.1173ms","start":"2026-07-16T16:48:02.090156Z","end":"2026-07-16T16:48:02.271273Z","steps":["trace[148643586] 'process raft request' (duration: 180.796794ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:02.481933Z","caller":"traceutil/trace.go:171","msg":"trace[1230427374] linearizableReadLoop","detail":"{readStateIndex:27897; appliedIndex:27896; }","duration":"206.041859ms","start":"2026-07-16T16:48:02.275868Z","end":"2026-07-16T16:48:02.48191Z","steps":["trace[1230427374] 'read index received' (duration: 205.831785ms)","trace[1230427374] 'applied index is now lower than readState.Index' (duration: 205.934µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:02.481989Z","caller":"traceutil/trace.go:171","msg":"trace[1565996202] transaction","detail":"{read_only:false; response_revision:27007; number_of_response:1; }","duration":"206.189172ms","start":"2026-07-16T16:48:02.27577Z","end":"2026-07-16T16:48:02.481959Z","steps":["trace[1565996202] 'process raft request' (duration: 205.900747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:02.482161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.272763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ac2dcb91-5149-4fd7-bf7e-2b467a76fdad\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-07-16T16:48:02.482205Z","caller":"traceutil/trace.go:171","msg":"trace[1249593955] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ac2dcb91-5149-4fd7-bf7e-2b467a76fdad; range_end:; response_count:1; response_revision:27007; }","duration":"206.354335ms","start":"2026-07-16T16:48:02.275837Z","end":"2026-07-16T16:48:02.482191Z","steps":["trace[1249593955] 'agreement among raft nodes before linearized reading' (duration: 206.202092ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:02.597498Z","caller":"traceutil/trace.go:171","msg":"trace[761145464] transaction","detail":"{read_only:false; response_revision:27008; number_of_response:1; }","duration":"317.810513ms","start":"2026-07-16T16:48:02.279666Z","end":"2026-07-16T16:48:02.597476Z","steps":["trace[761145464] 'process raft request' (duration: 317.589419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:02.597677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:48:02.279649Z","time spent":"317.950786ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:48:02.597843Z","caller":"traceutil/trace.go:171","msg":"trace[1656608616] linearizableReadLoop","detail":"{readStateIndex:27900; appliedIndex:27897; }","duration":"115.813791ms","start":"2026-07-16T16:48:02.482013Z","end":"2026-07-16T16:48:02.597827Z","steps":["trace[1656608616] 'read index received' (duration: 115.305011ms)","trace[1656608616] 'applied index is now lower than readState.Index' (duration: 508.21µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:48:02.598011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.72597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T16:48:02.59805Z","caller":"traceutil/trace.go:171","msg":"trace[808068692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27010; }","duration":"131.813982ms","start":"2026-07-16T16:48:02.466227Z","end":"2026-07-16T16:48:02.59804Z","steps":["trace[808068692] 'agreement among raft nodes before linearized reading' (duration: 131.668239ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:02.598148Z","caller":"traceutil/trace.go:171","msg":"trace[394061071] transaction","detail":"{read_only:false; response_revision:27009; number_of_response:1; }","duration":"314.713475ms","start":"2026-07-16T16:48:02.283425Z","end":"2026-07-16T16:48:02.598139Z","steps":["trace[394061071] 'process raft request' (duration: 313.986101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:02.598205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:48:02.283416Z","time spent":"314.751446ms","remote":"127.0.0.1:45932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10459,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:48:02.598316Z","caller":"traceutil/trace.go:171","msg":"trace[943986125] transaction","detail":"{read_only:false; response_revision:27010; number_of_response:1; }","duration":"305.860219ms","start":"2026-07-16T16:48:02.292311Z","end":"2026-07-16T16:48:02.598171Z","steps":["trace[943986125] 'process raft request' (duration: 305.390071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:02.598503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:48:02.292297Z","time spent":"306.100493ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:48:03.695219Z","caller":"traceutil/trace.go:171","msg":"trace[1425537831] transaction","detail":"{read_only:false; response_revision:27014; number_of_response:1; }","duration":"153.197864ms","start":"2026-07-16T16:48:03.54199Z","end":"2026-07-16T16:48:03.695187Z","steps":["trace[1425537831] 'process raft request' (duration: 141.25913ms)","trace[1425537831] 'compare' (duration: 11.552537ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:48:03.833548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.463167ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-16T16:48:03.833645Z","caller":"traceutil/trace.go:171","msg":"trace[362349725] linearizableReadLoop","detail":"{readStateIndex:27905; appliedIndex:27904; }","duration":"114.570447ms","start":"2026-07-16T16:48:03.719058Z","end":"2026-07-16T16:48:03.833629Z","steps":["trace[362349725] 'read index received' (duration: 40.781µs)","trace[362349725] 'applied index is now lower than readState.Index' (duration: 114.528076ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:48:03.834308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.21053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-07-16T16:48:03.834416Z","caller":"traceutil/trace.go:171","msg":"trace[142193614] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27014; }","duration":"115.356872ms","start":"2026-07-16T16:48:03.719027Z","end":"2026-07-16T16:48:03.834384Z","steps":["trace[142193614] 'agreement among raft nodes before linearized reading' (duration: 114.644458ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:06.789792Z","caller":"traceutil/trace.go:171","msg":"trace[1510775275] linearizableReadLoop","detail":"{readStateIndex:27936; appliedIndex:27935; }","duration":"115.80227ms","start":"2026-07-16T16:48:06.673953Z","end":"2026-07-16T16:48:06.789755Z","steps":["trace[1510775275] 'read index received' (duration: 115.481083ms)","trace[1510775275] 'applied index is now lower than readState.Index' (duration: 319.697µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:06.789839Z","caller":"traceutil/trace.go:171","msg":"trace[2108834720] transaction","detail":"{read_only:false; response_revision:27045; number_of_response:1; }","duration":"157.14541ms","start":"2026-07-16T16:48:06.632658Z","end":"2026-07-16T16:48:06.789803Z","steps":["trace[2108834720] 'process raft request' (duration: 156.800933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:06.790168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.167316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-07-16T16:48:06.790276Z","caller":"traceutil/trace.go:171","msg":"trace[1145307818] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:27045; }","duration":"116.34393ms","start":"2026-07-16T16:48:06.673907Z","end":"2026-07-16T16:48:06.790251Z","steps":["trace[1145307818] 'agreement among raft nodes before linearized reading' (duration: 115.991604ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:08.751616Z","caller":"traceutil/trace.go:171","msg":"trace[606335843] transaction","detail":"{read_only:false; response_revision:27066; number_of_response:1; }","duration":"133.482794ms","start":"2026-07-16T16:48:08.618084Z","end":"2026-07-16T16:48:08.751567Z","steps":["trace[606335843] 'process raft request' (duration: 91.241489ms)","trace[606335843] 'compare' (duration: 41.96558ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:09.723327Z","caller":"traceutil/trace.go:171","msg":"trace[1272045619] transaction","detail":"{read_only:false; response_revision:27068; number_of_response:1; }","duration":"156.550397ms","start":"2026-07-16T16:48:09.566731Z","end":"2026-07-16T16:48:09.723282Z","steps":["trace[1272045619] 'process raft request' (duration: 156.276732ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:22.049238Z","caller":"traceutil/trace.go:171","msg":"trace[598317459] transaction","detail":"{read_only:false; response_revision:27183; number_of_response:1; }","duration":"144.716634ms","start":"2026-07-16T16:48:21.904484Z","end":"2026-07-16T16:48:22.0492Z","steps":["trace[598317459] 'process raft request' (duration: 115.856141ms)","trace[598317459] 'compare' (duration: 28.409795ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:22.245781Z","caller":"traceutil/trace.go:171","msg":"trace[1115963556] transaction","detail":"{read_only:false; response_revision:27185; number_of_response:1; }","duration":"104.288774ms","start":"2026-07-16T16:48:22.14146Z","end":"2026-07-16T16:48:22.245749Z","steps":["trace[1115963556] 'process raft request' (duration: 65.928221ms)","trace[1115963556] 'compare' (duration: 38.117468ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:22.494663Z","caller":"traceutil/trace.go:171","msg":"trace[841621327] linearizableReadLoop","detail":"{readStateIndex:28081; appliedIndex:28080; }","duration":"156.858164ms","start":"2026-07-16T16:48:22.337773Z","end":"2026-07-16T16:48:22.494632Z","steps":["trace[841621327] 'read index received' (duration: 124.015076ms)","trace[841621327] 'applied index is now lower than readState.Index' (duration: 32.841708ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:22.494963Z","caller":"traceutil/trace.go:171","msg":"trace[735886546] transaction","detail":"{read_only:false; response_revision:27187; number_of_response:1; }","duration":"159.540893ms","start":"2026-07-16T16:48:22.335406Z","end":"2026-07-16T16:48:22.494947Z","steps":["trace[735886546] 'process raft request' (duration: 126.314207ms)","trace[735886546] 'compare' (duration: 32.122325ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:48:22.495155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.359914ms","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-07-16T16:48:22.49523Z","caller":"traceutil/trace.go:171","msg":"trace[1998679510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27187; }","duration":"157.472965ms","start":"2026-07-16T16:48:22.337736Z","end":"2026-07-16T16:48:22.495209Z","steps":["trace[1998679510] 'agreement among raft nodes before linearized reading' (duration: 157.231361ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:22.563372Z","caller":"traceutil/trace.go:171","msg":"trace[682817874] transaction","detail":"{read_only:false; response_revision:27188; number_of_response:1; }","duration":"220.891978ms","start":"2026-07-16T16:48:22.342448Z","end":"2026-07-16T16:48:22.56334Z","steps":["trace[682817874] 'process raft request' (duration: 220.759605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:22.563445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.831117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-16T16:48:22.563493Z","caller":"traceutil/trace.go:171","msg":"trace[249938551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27189; }","duration":"194.944719ms","start":"2026-07-16T16:48:22.368536Z","end":"2026-07-16T16:48:22.563481Z","steps":["trace[249938551] 'agreement among raft nodes before linearized reading' (duration: 194.797597ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:22.563573Z","caller":"traceutil/trace.go:171","msg":"trace[1236073409] transaction","detail":"{read_only:false; response_revision:27189; number_of_response:1; }","duration":"211.90718ms","start":"2026-07-16T16:48:22.35166Z","end":"2026-07-16T16:48:22.563567Z","steps":["trace[1236073409] 'process raft request' (duration: 211.634814ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:22.680388Z","caller":"traceutil/trace.go:171","msg":"trace[1038694472] transaction","detail":"{read_only:false; response_revision:27190; number_of_response:1; }","duration":"181.055049ms","start":"2026-07-16T16:48:22.499309Z","end":"2026-07-16T16:48:22.680364Z","steps":["trace[1038694472] 'process raft request' (duration: 117.576184ms)","trace[1038694472] 'compare' (duration: 62.923745ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:22.680503Z","caller":"traceutil/trace.go:171","msg":"trace[175263775] transaction","detail":"{read_only:false; response_revision:27191; number_of_response:1; }","duration":"111.603891ms","start":"2026-07-16T16:48:22.568866Z","end":"2026-07-16T16:48:22.68047Z","steps":["trace[175263775] 'process raft request' (duration: 111.263395ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:23.007162Z","caller":"traceutil/trace.go:171","msg":"trace[793059331] linearizableReadLoop","detail":"{readStateIndex:28087; appliedIndex:28086; }","duration":"144.46767ms","start":"2026-07-16T16:48:22.86266Z","end":"2026-07-16T16:48:23.007128Z","steps":["trace[793059331] 'read index received' (duration: 144.081723ms)","trace[793059331] 'applied index is now lower than readState.Index' (duration: 384.396µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:23.007209Z","caller":"traceutil/trace.go:171","msg":"trace[1809495953] transaction","detail":"{read_only:false; response_revision:27193; number_of_response:1; }","duration":"179.172043ms","start":"2026-07-16T16:48:22.827997Z","end":"2026-07-16T16:48:23.007169Z","steps":["trace[1809495953] 'process raft request' (duration: 178.883738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:23.007365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.690043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T16:48:23.007419Z","caller":"traceutil/trace.go:171","msg":"trace[1472295658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27193; }","duration":"144.811576ms","start":"2026-07-16T16:48:22.862591Z","end":"2026-07-16T16:48:23.007403Z","steps":["trace[1472295658] 'agreement among raft nodes before linearized reading' (duration: 144.655593ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:23.213801Z","caller":"traceutil/trace.go:171","msg":"trace[718600359] transaction","detail":"{read_only:false; response_revision:27194; number_of_response:1; }","duration":"201.090696ms","start":"2026-07-16T16:48:23.012672Z","end":"2026-07-16T16:48:23.213763Z","steps":["trace[718600359] 'process raft request' (duration: 115.434044ms)","trace[718600359] 'compare' (duration: 85.265245ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:23.213969Z","caller":"traceutil/trace.go:171","msg":"trace[1138320765] linearizableReadLoop","detail":"{readStateIndex:28088; appliedIndex:28087; }","duration":"111.380857ms","start":"2026-07-16T16:48:23.102557Z","end":"2026-07-16T16:48:23.213938Z","steps":["trace[1138320765] 'read index received' (duration: 25.555602ms)","trace[1138320765] 'applied index is now lower than readState.Index' (duration: 85.822815ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:48:23.214206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.628022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-cluster-api-proxy-8jmsq\" ","response":"range_response_count:1 size:5265"} {"level":"info","ts":"2026-07-16T16:48:23.214232Z","caller":"traceutil/trace.go:171","msg":"trace[1682709791] range","detail":"{range_begin:/registry/pods/openstack/magnum-cluster-api-proxy-8jmsq; range_end:; response_count:1; response_revision:27195; }","duration":"111.731894ms","start":"2026-07-16T16:48:23.102491Z","end":"2026-07-16T16:48:23.214223Z","steps":["trace[1682709791] 'agreement among raft nodes before linearized reading' (duration: 111.575551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:23.214399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.116672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-cluster-api-proxy-8jmsq.18c2d309d6aa2d89\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-07-16T16:48:23.214514Z","caller":"traceutil/trace.go:171","msg":"trace[1061785446] range","detail":"{range_begin:/registry/events/openstack/magnum-cluster-api-proxy-8jmsq.18c2d309d6aa2d89; range_end:; response_count:1; response_revision:27195; }","duration":"110.297685ms","start":"2026-07-16T16:48:23.104188Z","end":"2026-07-16T16:48:23.214486Z","steps":["trace[1061785446] 'agreement among raft nodes before linearized reading' (duration: 109.938239ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:23.596158Z","caller":"traceutil/trace.go:171","msg":"trace[1168699015] transaction","detail":"{read_only:false; response_revision:27197; number_of_response:1; }","duration":"375.189024ms","start":"2026-07-16T16:48:23.220943Z","end":"2026-07-16T16:48:23.596132Z","steps":["trace[1168699015] 'process raft request' (duration: 375.00354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:23.596329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:48:23.220916Z","time spent":"375.317786ms","remote":"127.0.0.1:45932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5306,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:48:34.874293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.033006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-16T16:48:34.874401Z","caller":"traceutil/trace.go:171","msg":"trace[2060485599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27286; }","duration":"140.184389ms","start":"2026-07-16T16:48:34.734193Z","end":"2026-07-16T16:48:34.874377Z","steps":["trace[2060485599] 'range keys from in-memory index tree' (duration: 139.877623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:34.874654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.858614ms","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-07-16T16:48:34.874762Z","caller":"traceutil/trace.go:171","msg":"trace[1212029524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27286; }","duration":"138.002857ms","start":"2026-07-16T16:48:34.736735Z","end":"2026-07-16T16:48:34.874738Z","steps":["trace[1212029524] 'range keys from in-memory index tree' (duration: 137.661811ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:35.010394Z","caller":"traceutil/trace.go:171","msg":"trace[1516687501] transaction","detail":"{read_only:false; response_revision:27287; number_of_response:1; }","duration":"133.623875ms","start":"2026-07-16T16:48:34.876744Z","end":"2026-07-16T16:48:35.010368Z","steps":["trace[1516687501] 'process raft request' (duration: 133.435732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:35.324116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.443487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T16:48:35.32437Z","caller":"traceutil/trace.go:171","msg":"trace[1643725656] transaction","detail":"{read_only:false; response_revision:27288; number_of_response:1; }","duration":"446.318033ms","start":"2026-07-16T16:48:34.878038Z","end":"2026-07-16T16:48:35.324356Z","steps":["trace[1643725656] 'process raft request' (duration: 274.556719ms)","trace[1643725656] 'compare' (duration: 170.812926ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:48:35.324435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:48:34.87801Z","time spent":"446.398784ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:48:35.324436Z","caller":"traceutil/trace.go:171","msg":"trace[802514985] transaction","detail":"{read_only:false; response_revision:27290; number_of_response:1; }","duration":"271.602244ms","start":"2026-07-16T16:48:35.052805Z","end":"2026-07-16T16:48:35.324407Z","steps":["trace[802514985] 'process raft request' (duration: 271.473511ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:35.324469Z","caller":"traceutil/trace.go:171","msg":"trace[1787740181] transaction","detail":"{read_only:false; response_revision:27289; number_of_response:1; }","duration":"445.514848ms","start":"2026-07-16T16:48:34.878919Z","end":"2026-07-16T16:48:35.324433Z","steps":["trace[1787740181] 'process raft request' (duration: 445.277483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:35.324683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:48:34.878907Z","time spent":"445.648561ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:48:45.495496Z","caller":"traceutil/trace.go:171","msg":"trace[1645021023] linearizableReadLoop","detail":"{readStateIndex:28276; appliedIndex:28275; }","duration":"111.727884ms","start":"2026-07-16T16:48:45.383738Z","end":"2026-07-16T16:48:45.495466Z","steps":["trace[1645021023] 'read index received' (duration: 111.375657ms)","trace[1645021023] 'applied index is now lower than readState.Index' (duration: 351.007µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:45.495644Z","caller":"traceutil/trace.go:171","msg":"trace[1617265971] transaction","detail":"{read_only:false; response_revision:27377; number_of_response:1; }","duration":"126.356759ms","start":"2026-07-16T16:48:45.369245Z","end":"2026-07-16T16:48:45.495602Z","steps":["trace[1617265971] 'process raft request' (duration: 125.978202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:45.495791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.139167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T16:48:45.495837Z","caller":"traceutil/trace.go:171","msg":"trace[1001564474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27377; }","duration":"107.229368ms","start":"2026-07-16T16:48:45.388596Z","end":"2026-07-16T16:48:45.495826Z","steps":["trace[1001564474] 'agreement among raft nodes before linearized reading' (duration: 107.035445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:45.49581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.049769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-16T16:48:45.495918Z","caller":"traceutil/trace.go:171","msg":"trace[48045190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27377; }","duration":"112.169531ms","start":"2026-07-16T16:48:45.383727Z","end":"2026-07-16T16:48:45.495897Z","steps":["trace[48045190] 'agreement among raft nodes before linearized reading' (duration: 111.910357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:45.495923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.267461ms","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-07-16T16:48:45.496018Z","caller":"traceutil/trace.go:171","msg":"trace[1072596630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27377; }","duration":"106.421264ms","start":"2026-07-16T16:48:45.38957Z","end":"2026-07-16T16:48:45.495991Z","steps":["trace[1072596630] 'agreement among raft nodes before linearized reading' (duration: 106.136489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:45.718759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.551753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T16:48:45.718898Z","caller":"traceutil/trace.go:171","msg":"trace[508068032] transaction","detail":"{read_only:false; response_revision:27378; number_of_response:1; }","duration":"218.255598ms","start":"2026-07-16T16:48:45.500591Z","end":"2026-07-16T16:48:45.718847Z","steps":["trace[508068032] 'process raft request' (duration: 66.526372ms)","trace[508068032] 'compare' (duration: 151.38009ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:45.723447Z","caller":"traceutil/trace.go:171","msg":"trace[389908873] transaction","detail":"{read_only:false; response_revision:27379; number_of_response:1; }","duration":"222.660081ms","start":"2026-07-16T16:48:45.500771Z","end":"2026-07-16T16:48:45.723431Z","steps":["trace[389908873] 'process raft request' (duration: 222.476078ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:45.723622Z","caller":"traceutil/trace.go:171","msg":"trace[1685549648] transaction","detail":"{read_only:false; response_revision:27380; number_of_response:1; }","duration":"222.791574ms","start":"2026-07-16T16:48:45.500796Z","end":"2026-07-16T16:48:45.723588Z","steps":["trace[1685549648] 'process raft request' (duration: 222.59248ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:46.396544Z","caller":"traceutil/trace.go:171","msg":"trace[236553151] transaction","detail":"{read_only:false; response_revision:27382; number_of_response:1; }","duration":"243.450542ms","start":"2026-07-16T16:48:46.153067Z","end":"2026-07-16T16:48:46.396518Z","steps":["trace[236553151] 'process raft request' (duration: 199.057227ms)","trace[236553151] 'compare' (duration: 44.184661ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:48:46.670056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.561808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T16:48:46.670345Z","caller":"traceutil/trace.go:171","msg":"trace[428289581] transaction","detail":"{read_only:false; response_revision:27383; number_of_response:1; }","duration":"465.29646ms","start":"2026-07-16T16:48:46.205002Z","end":"2026-07-16T16:48:46.670299Z","steps":["trace[428289581] 'process raft request' (duration: 342.357975ms)","trace[428289581] 'compare' (duration: 122.363664ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T16:48:46.670491Z","caller":"traceutil/trace.go:171","msg":"trace[328929064] transaction","detail":"{read_only:false; response_revision:27384; number_of_response:1; }","duration":"452.661562ms","start":"2026-07-16T16:48:46.217804Z","end":"2026-07-16T16:48:46.670466Z","steps":["trace[328929064] 'process raft request' (duration: 452.368397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:46.670584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:48:46.204971Z","time spent":"465.525303ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:48:46.670597Z","caller":"traceutil/trace.go:171","msg":"trace[931361945] linearizableReadLoop","detail":"{readStateIndex:28285; appliedIndex:28281; }","duration":"102.4922ms","start":"2026-07-16T16:48:46.568083Z","end":"2026-07-16T16:48:46.670575Z","steps":["trace[931361945] 'read index received' (duration: 36.051µs)","trace[931361945] 'applied index is now lower than readState.Index' (duration: 102.454539ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T16:48:46.670766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:48:46.217788Z","time spent":"452.772934ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T16:48:46.67066Z","caller":"traceutil/trace.go:171","msg":"trace[1948966892] transaction","detail":"{read_only:false; response_revision:27385; number_of_response:1; }","duration":"337.498844ms","start":"2026-07-16T16:48:46.333134Z","end":"2026-07-16T16:48:46.670633Z","steps":["trace[1948966892] 'process raft request' (duration: 337.26961ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:48:46.67079Z","caller":"traceutil/trace.go:171","msg":"trace[550677182] transaction","detail":"{read_only:false; response_revision:27386; number_of_response:1; }","duration":"333.01248ms","start":"2026-07-16T16:48:46.337735Z","end":"2026-07-16T16:48:46.670747Z","steps":["trace[550677182] 'process raft request' (duration: 332.733275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:46.670916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:48:46.333106Z","time spent":"337.721968ms","remote":"127.0.0.1:46040","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-07-16T16:48:46.670921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.838386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T16:48:46.670974Z","caller":"traceutil/trace.go:171","msg":"trace[1171621286] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:27386; }","duration":"102.917328ms","start":"2026-07-16T16:48:46.568038Z","end":"2026-07-16T16:48:46.670956Z","steps":["trace[1171621286] 'agreement among raft nodes before linearized reading' (duration: 102.832496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T16:48:46.671007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T16:48:46.337698Z","time spent":"333.195663ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T16:48:46.950162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.815086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18466"} {"level":"info","ts":"2026-07-16T16:48:46.950282Z","caller":"traceutil/trace.go:171","msg":"trace[1252646696] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27389; }","duration":"179.988159ms","start":"2026-07-16T16:48:46.770264Z","end":"2026-07-16T16:48:46.950253Z","steps":["trace[1252646696] 'range keys from in-memory index tree' (duration: 179.569821ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T16:49:05.402944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24620} {"level":"info","ts":"2026-07-16T16:49:05.643837Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24620,"took":"236.309969ms","hash":3627847789,"current-db-size-bytes":57114624,"current-db-size":"57 MB","current-db-size-in-use-bytes":36159488,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-16T16:49:05.643893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3627847789,"revision":24620,"compact-revision":21700} {"level":"info","ts":"2026-07-16T16:51:36.193112Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":30006,"local-member-snapshot-index":20005,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-16T16:51:36.198284Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30006} {"level":"info","ts":"2026-07-16T16:51:36.19842Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25006} {"level":"info","ts":"2026-07-16T16:54:05.411218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27537} {"level":"info","ts":"2026-07-16T16:54:05.550418Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27537,"took":"135.263037ms","hash":3177366992,"current-db-size-bytes":57114624,"current-db-size":"57 MB","current-db-size-in-use-bytes":35762176,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-16T16:54:05.550498Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3177366992,"revision":27537,"compact-revision":24620} {"level":"info","ts":"2026-07-16T16:59:05.418915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30446} {"level":"info","ts":"2026-07-16T16:59:05.555045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30446,"took":"131.282209ms","hash":926365475,"current-db-size-bytes":57114624,"current-db-size":"57 MB","current-db-size-in-use-bytes":31821824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-16T16:59:05.555101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":926365475,"revision":30446,"compact-revision":27537} {"level":"info","ts":"2026-07-16T17:04:05.427366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32722} {"level":"info","ts":"2026-07-16T17:04:05.591648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32722,"took":"159.328691ms","hash":505003675,"current-db-size-bytes":58159104,"current-db-size":"58 MB","current-db-size-in-use-bytes":31625216,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-16T17:04:05.591729Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":505003675,"revision":32722,"compact-revision":30446} {"level":"info","ts":"2026-07-16T17:09:05.435108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35023} {"level":"info","ts":"2026-07-16T17:09:05.5679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35023,"took":"127.401031ms","hash":1628750720,"current-db-size-bytes":58159104,"current-db-size":"58 MB","current-db-size-in-use-bytes":33370112,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-16T17:09:05.567988Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1628750720,"revision":35023,"compact-revision":32722} {"level":"info","ts":"2026-07-16T17:12:08.880607Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":40007,"local-member-snapshot-index":30006,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-16T17:12:08.885358Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40007} {"level":"info","ts":"2026-07-16T17:12:08.88548Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35007} {"level":"info","ts":"2026-07-16T17:14:05.441321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37368} {"level":"info","ts":"2026-07-16T17:14:05.565979Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37368,"took":"120.238483ms","hash":30492411,"current-db-size-bytes":58159104,"current-db-size":"58 MB","current-db-size-in-use-bytes":32043008,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-16T17:14:05.566044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":30492411,"revision":37368,"compact-revision":35023} {"level":"info","ts":"2026-07-16T17:19:05.447342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39653} {"level":"info","ts":"2026-07-16T17:19:05.5765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39653,"took":"123.75917ms","hash":2115882574,"current-db-size-bytes":58159104,"current-db-size":"58 MB","current-db-size-in-use-bytes":30650368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-16T17:19:05.576572Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2115882574,"revision":39653,"compact-revision":37368}