{"level":"warn","ts":"2026-07-08T02:16:03.319912Z","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-08T02:16:03.320034Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-08T02:16:03.320128Z","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-08T02:16:03.32015Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-08T02:16:03.320186Z","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-08T02:16:03.321456Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-07-08T02:16:03.321603Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-08T02:16:03.329048Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.024637ms"} {"level":"info","ts":"2026-07-08T02:16:03.340943Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-07-08T02:16:03.341117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T02:16:03.341212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-07-08T02:16:03.341244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T02:16:03.341291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-07-08T02:16:03.34138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-07-08T02:16:03.34921Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T02:16:03.351273Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T02:16:03.352552Z","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-08T02:16:03.354664Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T02:16:03.354973Z","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-08T02:16:03.354958Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T02:16:03.355111Z","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-08T02:16:03.355126Z","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-08T02:16:03.356404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-07-08T02:16:03.357068Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-08T02:16:03.359235Z","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-08T02:16:03.35934Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-08T02:16:03.360117Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-08T02:16:03.359585Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T02:16:03.359631Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T02:16:03.942147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T02:16:03.942316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T02:16:03.942432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-07-08T02:16:03.942526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-07-08T02:16:03.942587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-08T02:16:03.942706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-07-08T02:16:03.942767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-08T02:16:03.944006Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:16:03.944983Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T02:16:03.94507Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T02:16:03.945005Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T02:16:03.945483Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T02:16:03.945563Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T02:16:03.945958Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:16:03.946238Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:16:03.946322Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:16:03.948461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T02:16:03.95578Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-07-08T02:16:48.894146Z","caller":"traceutil/trace.go:171","msg":"trace[467876754] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"199.735364ms","start":"2026-07-08T02:16:48.694387Z","end":"2026-07-08T02:16:48.894122Z","steps":["trace[467876754] 'process raft request' (duration: 199.23938ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:16:49.081638Z","caller":"traceutil/trace.go:171","msg":"trace[1785571056] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"125.985646ms","start":"2026-07-08T02:16:48.955589Z","end":"2026-07-08T02:16:49.081575Z","steps":["trace[1785571056] 'process raft request' (duration: 125.643016ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:16:49.616098Z","caller":"traceutil/trace.go:171","msg":"trace[629261872] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"105.520476ms","start":"2026-07-08T02:16:49.510536Z","end":"2026-07-08T02:16:49.616057Z","steps":["trace[629261872] 'process raft request' (duration: 104.436425ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:18:22.728932Z","caller":"traceutil/trace.go:171","msg":"trace[1806870467] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"261.437186ms","start":"2026-07-08T02:18:22.46746Z","end":"2026-07-08T02:18:22.728897Z","steps":["trace[1806870467] 'process raft request' (duration: 220.204875ms)","trace[1806870467] 'compare' (duration: 40.992414ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:18:22.729271Z","caller":"traceutil/trace.go:171","msg":"trace[1496333300] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"213.13446ms","start":"2026-07-08T02:18:22.51609Z","end":"2026-07-08T02:18:22.729224Z","steps":["trace[1496333300] 'process raft request' (duration: 212.747958ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:18:22.734737Z","caller":"traceutil/trace.go:171","msg":"trace[614345942] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1903; }","duration":"185.171293ms","start":"2026-07-08T02:18:22.549529Z","end":"2026-07-08T02:18:22.7347Z","steps":["trace[614345942] 'read index received' (duration: 138.155543ms)","trace[614345942] 'applied index is now lower than readState.Index' (duration: 47.01364ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:18:22.735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.444581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d2927c2c-04c9-42b3-9e4d-15ca984cbf63\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-08T02:18:22.735154Z","caller":"traceutil/trace.go:171","msg":"trace[412106786] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d2927c2c-04c9-42b3-9e4d-15ca984cbf63; range_end:; response_count:1; response_revision:1835; }","duration":"185.616716ms","start":"2026-07-08T02:18:22.549508Z","end":"2026-07-08T02:18:22.735125Z","steps":["trace[412106786] 'agreement among raft nodes before linearized reading' (duration: 185.305187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:18:37.310482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.264445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-08T02:18:37.310653Z","caller":"traceutil/trace.go:171","msg":"trace[328601043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1961; }","duration":"189.482441ms","start":"2026-07-08T02:18:37.121137Z","end":"2026-07-08T02:18:37.310619Z","steps":["trace[328601043] 'range keys from in-memory index tree' (duration: 189.056279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:18:37.310715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.960187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-08T02:18:37.310861Z","caller":"traceutil/trace.go:171","msg":"trace[343919968] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1961; }","duration":"196.171143ms","start":"2026-07-08T02:18:37.114647Z","end":"2026-07-08T02:18:37.310819Z","steps":["trace[343919968] 'range keys from in-memory index tree' (duration: 195.697469ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:19:08.093952Z","caller":"traceutil/trace.go:171","msg":"trace[161250819] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"170.608738ms","start":"2026-07-08T02:19:07.923306Z","end":"2026-07-08T02:19:08.093914Z","steps":["trace[161250819] 'process raft request' (duration: 143.23142ms)","trace[161250819] 'compare' (duration: 27.137901ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:19:14.309943Z","caller":"traceutil/trace.go:171","msg":"trace[42038126] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2253; }","duration":"166.287093ms","start":"2026-07-08T02:19:14.143628Z","end":"2026-07-08T02:19:14.309916Z","steps":["trace[42038126] 'read index received' (duration: 165.936463ms)","trace[42038126] 'applied index is now lower than readState.Index' (duration: 349.76µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:19:14.310206Z","caller":"traceutil/trace.go:171","msg":"trace[822903592] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"192.492646ms","start":"2026-07-08T02:19:14.117685Z","end":"2026-07-08T02:19:14.310178Z","steps":["trace[822903592] 'process raft request' (duration: 191.949231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:19:14.310422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.758617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:19:14.310599Z","caller":"traceutil/trace.go:171","msg":"trace[1369880176] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:2172; }","duration":"166.915601ms","start":"2026-07-08T02:19:14.143605Z","end":"2026-07-08T02:19:14.310521Z","steps":["trace[1369880176] 'agreement among raft nodes before linearized reading' (duration: 166.682355ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:19:19.816004Z","caller":"traceutil/trace.go:171","msg":"trace[1491302726] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2281; }","duration":"145.235158ms","start":"2026-07-08T02:19:19.670692Z","end":"2026-07-08T02:19:19.815927Z","steps":["trace[1491302726] 'read index received' (duration: 145.118325ms)","trace[1491302726] 'applied index is now lower than readState.Index' (duration: 115.463µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:19:19.816086Z","caller":"traceutil/trace.go:171","msg":"trace[811994355] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"229.936865ms","start":"2026-07-08T02:19:19.586091Z","end":"2026-07-08T02:19:19.816028Z","steps":["trace[811994355] 'process raft request' (duration: 229.679118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:19:19.816239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.536887ms","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-08T02:19:19.81631Z","caller":"traceutil/trace.go:171","msg":"trace[1925437809] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2199; }","duration":"145.648059ms","start":"2026-07-08T02:19:19.670645Z","end":"2026-07-08T02:19:19.816293Z","steps":["trace[1925437809] 'agreement among raft nodes before linearized reading' (duration: 145.444394ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:20:39.107366Z","caller":"traceutil/trace.go:171","msg":"trace[84615290] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"123.374357ms","start":"2026-07-08T02:20:38.983951Z","end":"2026-07-08T02:20:39.107326Z","steps":["trace[84615290] 'process raft request' (duration: 123.12257ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:20:39.310185Z","caller":"traceutil/trace.go:171","msg":"trace[309099072] linearizableReadLoop","detail":"{readStateIndex:2930; appliedIndex:2929; }","duration":"108.449698ms","start":"2026-07-08T02:20:39.201687Z","end":"2026-07-08T02:20:39.310137Z","steps":["trace[309099072] 'read index received' (duration: 95.976221ms)","trace[309099072] 'applied index is now lower than readState.Index' (duration: 12.471527ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:20:39.310307Z","caller":"traceutil/trace.go:171","msg":"trace[720931881] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"253.034293ms","start":"2026-07-08T02:20:39.057216Z","end":"2026-07-08T02:20:39.31025Z","steps":["trace[720931881] 'process raft request' (duration: 240.270368ms)","trace[720931881] 'compare' (duration: 12.312702ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:20:39.31053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.807138ms","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-08T02:20:39.310643Z","caller":"traceutil/trace.go:171","msg":"trace[367642529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2823; }","duration":"108.962852ms","start":"2026-07-08T02:20:39.201651Z","end":"2026-07-08T02:20:39.310614Z","steps":["trace[367642529] 'agreement among raft nodes before linearized reading' (duration: 108.631443ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:20:39.531319Z","caller":"traceutil/trace.go:171","msg":"trace[501111416] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"215.684244ms","start":"2026-07-08T02:20:39.315588Z","end":"2026-07-08T02:20:39.531272Z","steps":["trace[501111416] 'process raft request' (duration: 164.328232ms)","trace[501111416] 'compare' (duration: 51.138766ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:20:44.767166Z","caller":"traceutil/trace.go:171","msg":"trace[820499326] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"100.606054ms","start":"2026-07-08T02:20:44.666527Z","end":"2026-07-08T02:20:44.767133Z","steps":["trace[820499326] 'process raft request' (duration: 100.425069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:22:19.339555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221030493465185,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T02:22:19.435946Z","caller":"traceutil/trace.go:171","msg":"trace[913137027] linearizableReadLoop","detail":"{readStateIndex:3483; appliedIndex:3481; }","duration":"597.493948ms","start":"2026-07-08T02:22:18.838423Z","end":"2026-07-08T02:22:19.435917Z","steps":["trace[913137027] 'read index received' (duration: 596.418777ms)","trace[913137027] 'applied index is now lower than readState.Index' (duration: 1.074141ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:22:19.436069Z","caller":"traceutil/trace.go:171","msg":"trace[123246057] transaction","detail":"{read_only:false; response_revision:3355; number_of_response:1; }","duration":"293.148424ms","start":"2026-07-08T02:22:19.142889Z","end":"2026-07-08T02:22:19.436038Z","steps":["trace[123246057] 'process raft request' (duration: 292.926468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:22:19.436144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.707354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:22:19.436174Z","caller":"traceutil/trace.go:171","msg":"trace[1079001843] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:3355; }","duration":"597.801627ms","start":"2026-07-08T02:22:18.838363Z","end":"2026-07-08T02:22:19.436165Z","steps":["trace[1079001843] 'agreement among raft nodes before linearized reading' (duration: 597.702034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:22:19.436274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.208556ms","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":"warn","ts":"2026-07-08T02:22:19.436316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.340136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:22:19.436338Z","caller":"traceutil/trace.go:171","msg":"trace[1058198091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3355; }","duration":"499.320959ms","start":"2026-07-08T02:22:18.937Z","end":"2026-07-08T02:22:19.436321Z","steps":["trace[1058198091] 'agreement among raft nodes before linearized reading' (duration: 499.098773ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:22:19.436356Z","caller":"traceutil/trace.go:171","msg":"trace[164557991] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:3355; }","duration":"359.445549ms","start":"2026-07-08T02:22:19.076896Z","end":"2026-07-08T02:22:19.436341Z","steps":["trace[164557991] 'agreement among raft nodes before linearized reading' (duration: 359.352546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:22:19.436653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.79089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T02:22:19.436198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:22:18.838332Z","time spent":"597.860509ms","remote":"127.0.0.1:43012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":22,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-07-08T02:22:19.436395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:22:18.936976Z","time spent":"499.406862ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T02:22:19.436745Z","caller":"traceutil/trace.go:171","msg":"trace[1736625041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3355; }","duration":"416.927523ms","start":"2026-07-08T02:22:19.01979Z","end":"2026-07-08T02:22:19.436717Z","steps":["trace[1736625041] 'agreement among raft nodes before linearized reading' (duration: 416.764729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:22:19.436395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:22:19.076868Z","time spent":"359.517191ms","remote":"127.0.0.1:50120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-08T02:22:19.436844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:22:19.019763Z","time spent":"417.025356ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T02:22:19.43599Z","caller":"traceutil/trace.go:171","msg":"trace[1616868534] transaction","detail":"{read_only:false; response_revision:3354; number_of_response:1; }","duration":"656.650899ms","start":"2026-07-08T02:22:18.779315Z","end":"2026-07-08T02:22:19.435966Z","steps":["trace[1616868534] 'process raft request' (duration: 655.504106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:22:19.438181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:22:18.779291Z","time spent":"657.774391ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:23:26.101254Z","caller":"traceutil/trace.go:171","msg":"trace[787725067] transaction","detail":"{read_only:false; response_revision:3900; number_of_response:1; }","duration":"139.059442ms","start":"2026-07-08T02:23:25.962149Z","end":"2026-07-08T02:23:26.101209Z","steps":["trace[787725067] 'process raft request' (duration: 138.728613ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:38.664628Z","caller":"traceutil/trace.go:171","msg":"trace[1169886327] transaction","detail":"{read_only:false; response_revision:4392; number_of_response:1; }","duration":"142.849623ms","start":"2026-07-08T02:24:38.521744Z","end":"2026-07-08T02:24:38.664594Z","steps":["trace[1169886327] 'process raft request' (duration: 118.879704ms)","trace[1169886327] 'compare' (duration: 23.65804ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:38.664642Z","caller":"traceutil/trace.go:171","msg":"trace[4885536] transaction","detail":"{read_only:false; response_revision:4393; number_of_response:1; }","duration":"138.863348ms","start":"2026-07-08T02:24:38.525747Z","end":"2026-07-08T02:24:38.664611Z","steps":["trace[4885536] 'process raft request' (duration: 138.744675ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:47.843241Z","caller":"traceutil/trace.go:171","msg":"trace[250734909] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"192.236502ms","start":"2026-07-08T02:24:47.650936Z","end":"2026-07-08T02:24:47.843172Z","steps":["trace[250734909] 'process raft request' (duration: 191.975855ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:47.843378Z","caller":"traceutil/trace.go:171","msg":"trace[659256797] linearizableReadLoop","detail":"{readStateIndex:4987; appliedIndex:4985; }","duration":"151.672597ms","start":"2026-07-08T02:24:47.691648Z","end":"2026-07-08T02:24:47.84332Z","steps":["trace[659256797] 'read index received' (duration: 151.186733ms)","trace[659256797] 'applied index is now lower than readState.Index' (duration: 481.683µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:47.843487Z","caller":"traceutil/trace.go:171","msg":"trace[1486083821] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"187.772653ms","start":"2026-07-08T02:24:47.655676Z","end":"2026-07-08T02:24:47.843449Z","steps":["trace[1486083821] 'process raft request' (duration: 187.420953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:47.843766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.499516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:24:47.843824Z","caller":"traceutil/trace.go:171","msg":"trace[672175719] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:0; response_revision:4801; }","duration":"147.594179ms","start":"2026-07-08T02:24:47.696211Z","end":"2026-07-08T02:24:47.843805Z","steps":["trace[672175719] 'agreement among raft nodes before linearized reading' (duration: 147.375493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:47.84389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.128219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:24:47.84404Z","caller":"traceutil/trace.go:171","msg":"trace[667228588] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:4801; }","duration":"152.407037ms","start":"2026-07-08T02:24:47.691612Z","end":"2026-07-08T02:24:47.844019Z","steps":["trace[667228588] 'agreement among raft nodes before linearized reading' (duration: 151.939604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:53.138196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.680033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-07-08T02:24:53.138307Z","caller":"traceutil/trace.go:171","msg":"trace[660685292] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4961; }","duration":"107.861358ms","start":"2026-07-08T02:24:53.030425Z","end":"2026-07-08T02:24:53.138286Z","steps":["trace[660685292] 'agreement among raft nodes before linearized reading' (duration: 107.537449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:53.346783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.90043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:24:53.347399Z","caller":"traceutil/trace.go:171","msg":"trace[588918100] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"203.780903ms","start":"2026-07-08T02:24:53.143599Z","end":"2026-07-08T02:24:53.34738Z","steps":["trace[588918100] 'process raft request' (duration: 48.688679ms)","trace[588918100] 'compare' (duration: 153.643252ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:53.347528Z","caller":"traceutil/trace.go:171","msg":"trace[219906580] linearizableReadLoop","detail":"{readStateIndex:5154; appliedIndex:5153; }","duration":"200.263233ms","start":"2026-07-08T02:24:53.147256Z","end":"2026-07-08T02:24:53.347519Z","steps":["trace[219906580] 'read index received' (duration: 44.947062ms)","trace[219906580] 'applied index is now lower than readState.Index' (duration: 155.314881ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:53.347704Z","caller":"traceutil/trace.go:171","msg":"trace[795881704] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"128.919413ms","start":"2026-07-08T02:24:53.218778Z","end":"2026-07-08T02:24:53.347697Z","steps":["trace[795881704] 'process raft request' (duration: 128.136251ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:53.34789Z","caller":"traceutil/trace.go:171","msg":"trace[1806067752] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"106.375665ms","start":"2026-07-08T02:24:53.241508Z","end":"2026-07-08T02:24:53.347884Z","steps":["trace[1806067752] 'process raft request' (duration: 105.47554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:53.348188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.922491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16453"} {"level":"info","ts":"2026-07-08T02:24:53.348219Z","caller":"traceutil/trace.go:171","msg":"trace[1934329573] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4966; }","duration":"201.000574ms","start":"2026-07-08T02:24:53.147209Z","end":"2026-07-08T02:24:53.34821Z","steps":["trace[1934329573] 'agreement among raft nodes before linearized reading' (duration: 200.87529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:53.348395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.532643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11454"} {"level":"info","ts":"2026-07-08T02:24:53.348423Z","caller":"traceutil/trace.go:171","msg":"trace[1928784281] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4966; }","duration":"198.579704ms","start":"2026-07-08T02:24:53.149837Z","end":"2026-07-08T02:24:53.348416Z","steps":["trace[1928784281] 'agreement among raft nodes before linearized reading' (duration: 198.503922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:53.34877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.695863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-08T02:24:53.3488Z","caller":"traceutil/trace.go:171","msg":"trace[80421009] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4966; }","duration":"122.726594ms","start":"2026-07-08T02:24:53.226066Z","end":"2026-07-08T02:24:53.348793Z","steps":["trace[80421009] 'agreement among raft nodes before linearized reading' (duration: 122.644562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:53.349322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.38425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-07-08T02:24:53.349429Z","caller":"traceutil/trace.go:171","msg":"trace[1916032553] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4966; }","duration":"181.574746ms","start":"2026-07-08T02:24:53.167831Z","end":"2026-07-08T02:24:53.349406Z","steps":["trace[1916032553] 'agreement among raft nodes before linearized reading' (duration: 180.981758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:58.283138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.239779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:24:58.283294Z","caller":"traceutil/trace.go:171","msg":"trace[184530339] linearizableReadLoop","detail":"{readStateIndex:5325; appliedIndex:5324; }","duration":"133.861989ms","start":"2026-07-08T02:24:58.149408Z","end":"2026-07-08T02:24:58.28327Z","steps":["trace[184530339] 'read index received' (duration: 7.28017ms)","trace[184530339] 'applied index is now lower than readState.Index' (duration: 126.580279ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:58.283359Z","caller":"traceutil/trace.go:171","msg":"trace[1809385870] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"207.915157ms","start":"2026-07-08T02:24:58.075394Z","end":"2026-07-08T02:24:58.283309Z","steps":["trace[1809385870] 'process raft request' (duration: 81.372089ms)","trace[1809385870] 'compare' (duration: 126.120036ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:24:58.283528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.089665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a1a34ba7-b51d-41c1-8b8e-851e86a2866c\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-07-08T02:24:58.283635Z","caller":"traceutil/trace.go:171","msg":"trace[1751107333] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a1a34ba7-b51d-41c1-8b8e-851e86a2866c; range_end:; response_count:1; response_revision:5128; }","duration":"134.26658ms","start":"2026-07-08T02:24:58.149343Z","end":"2026-07-08T02:24:58.283609Z","steps":["trace[1751107333] 'agreement among raft nodes before linearized reading' (duration: 133.980982ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:25:11.295067Z","caller":"traceutil/trace.go:171","msg":"trace[1685294806] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"202.979857ms","start":"2026-07-08T02:25:11.09205Z","end":"2026-07-08T02:25:11.29503Z","steps":["trace[1685294806] 'process raft request' (duration: 164.315485ms)","trace[1685294806] 'compare' (duration: 38.297921ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:25:19.348384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.961041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-969dace9-9ed2-4131-a235-14a3ecf99dac\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-07-08T02:25:19.348512Z","caller":"traceutil/trace.go:171","msg":"trace[2101795501] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-969dace9-9ed2-4131-a235-14a3ecf99dac; range_end:; response_count:1; response_revision:5560; }","duration":"142.156256ms","start":"2026-07-08T02:25:19.206329Z","end":"2026-07-08T02:25:19.348485Z","steps":["trace[2101795501] 'range keys from in-memory index tree' (duration: 141.688983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:25:19.348615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.970756ms","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-08T02:25:19.348738Z","caller":"traceutil/trace.go:171","msg":"trace[2021538319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5560; }","duration":"210.149361ms","start":"2026-07-08T02:25:19.138557Z","end":"2026-07-08T02:25:19.348706Z","steps":["trace[2021538319] 'range keys from in-memory index tree' (duration: 209.715709ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:25:19.589664Z","caller":"traceutil/trace.go:171","msg":"trace[1088089438] transaction","detail":"{read_only:false; response_revision:5564; number_of_response:1; }","duration":"138.010937ms","start":"2026-07-08T02:25:19.451624Z","end":"2026-07-08T02:25:19.589635Z","steps":["trace[1088089438] 'process raft request' (duration: 137.862473ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:25:30.706639Z","caller":"traceutil/trace.go:171","msg":"trace[2128411928] transaction","detail":"{read_only:false; response_revision:5704; number_of_response:1; }","duration":"113.199835ms","start":"2026-07-08T02:25:30.593394Z","end":"2026-07-08T02:25:30.706594Z","steps":["trace[2128411928] 'process raft request' (duration: 82.761869ms)","trace[2128411928] 'compare' (duration: 30.215849ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:26:03.979166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2960} {"level":"info","ts":"2026-07-08T02:26:04.073492Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2960,"took":"92.487014ms","hash":3209103892,"current-db-size-bytes":57352192,"current-db-size":"57 MB","current-db-size-in-use-bytes":33144832,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-08T02:26:04.073584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3209103892,"revision":2960,"compact-revision":-1} {"level":"info","ts":"2026-07-08T02:31:03.988353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5999} {"level":"info","ts":"2026-07-08T02:31:04.085995Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5999,"took":"95.2113ms","hash":2773856633,"current-db-size-bytes":57352192,"current-db-size":"57 MB","current-db-size-in-use-bytes":21684224,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-08T02:31:04.086097Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2773856633,"revision":5999,"compact-revision":2960} {"level":"info","ts":"2026-07-08T02:31:38.969845Z","caller":"traceutil/trace.go:171","msg":"trace[359339215] transaction","detail":"{read_only:false; response_revision:8440; number_of_response:1; }","duration":"368.188392ms","start":"2026-07-08T02:31:38.601625Z","end":"2026-07-08T02:31:38.969813Z","steps":["trace[359339215] 'process raft request' (duration: 367.972766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:38.970027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:38.601599Z","time spent":"368.339806ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:31:38.971464Z","caller":"traceutil/trace.go:171","msg":"trace[210601079] transaction","detail":"{read_only:false; response_revision:8441; number_of_response:1; }","duration":"160.035661ms","start":"2026-07-08T02:31:38.8114Z","end":"2026-07-08T02:31:38.971436Z","steps":["trace[210601079] 'process raft request' (duration: 159.884157ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:50.626184Z","caller":"traceutil/trace.go:171","msg":"trace[1388172438] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"413.168361ms","start":"2026-07-08T02:31:50.212988Z","end":"2026-07-08T02:31:50.626156Z","steps":["trace[1388172438] 'process raft request' (duration: 412.872093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:50.626361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:50.212959Z","time spent":"413.304174ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:31:51.931238Z","caller":"traceutil/trace.go:171","msg":"trace[1666770043] linearizableReadLoop","detail":"{readStateIndex:8816; appliedIndex:8815; }","duration":"310.430794ms","start":"2026-07-08T02:31:51.620779Z","end":"2026-07-08T02:31:51.93121Z","steps":["trace[1666770043] 'read index received' (duration: 310.267969ms)","trace[1666770043] 'applied index is now lower than readState.Index' (duration: 161.005µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:51.931363Z","caller":"traceutil/trace.go:171","msg":"trace[1463017785] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"311.986319ms","start":"2026-07-08T02:31:51.61934Z","end":"2026-07-08T02:31:51.931326Z","steps":["trace[1463017785] 'process raft request' (duration: 311.66657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:51.931455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.532909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:31:51.931505Z","caller":"traceutil/trace.go:171","msg":"trace[350353251] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:8509; }","duration":"148.640782ms","start":"2026-07-08T02:31:51.782852Z","end":"2026-07-08T02:31:51.931493Z","steps":["trace[350353251] 'agreement among raft nodes before linearized reading' (duration: 148.542449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:51.931532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:51.61932Z","time spent":"312.127453ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:31:51.931645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.832516ms","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-08T02:31:51.931771Z","caller":"traceutil/trace.go:171","msg":"trace[1029973938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8509; }","duration":"311.003151ms","start":"2026-07-08T02:31:51.620732Z","end":"2026-07-08T02:31:51.931735Z","steps":["trace[1029973938] 'agreement among raft nodes before linearized reading' (duration: 310.670181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:51.931854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:51.620712Z","time spent":"311.126614ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T02:31:53.171549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.75528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:31:53.171645Z","caller":"traceutil/trace.go:171","msg":"trace[235651220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8514; }","duration":"153.500313ms","start":"2026-07-08T02:31:53.018125Z","end":"2026-07-08T02:31:53.171625Z","steps":["trace[235651220] 'range keys from in-memory index tree' (duration: 152.641398ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:32:20.308242Z","caller":"traceutil/trace.go:171","msg":"trace[215900480] transaction","detail":"{read_only:false; response_revision:8718; number_of_response:1; }","duration":"216.851903ms","start":"2026-07-08T02:32:20.091379Z","end":"2026-07-08T02:32:20.308231Z","steps":["trace[215900480] 'process raft request' (duration: 215.473712ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:32:20.307855Z","caller":"traceutil/trace.go:171","msg":"trace[2104915586] transaction","detail":"{read_only:false; response_revision:8719; number_of_response:1; }","duration":"208.280765ms","start":"2026-07-08T02:32:20.099557Z","end":"2026-07-08T02:32:20.307837Z","steps":["trace[2104915586] 'process raft request' (duration: 208.233903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:32:24.32209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.502414ms","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-08T02:32:24.322225Z","caller":"traceutil/trace.go:171","msg":"trace[934508021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8737; }","duration":"178.72206ms","start":"2026-07-08T02:32:24.143473Z","end":"2026-07-08T02:32:24.322195Z","steps":["trace[934508021] 'range keys from in-memory index tree' (duration: 178.213065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:32:33.824788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.74594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:32:33.824921Z","caller":"traceutil/trace.go:171","msg":"trace[188113818] linearizableReadLoop","detail":"{readStateIndex:9156; appliedIndex:9155; }","duration":"241.986868ms","start":"2026-07-08T02:32:33.582912Z","end":"2026-07-08T02:32:33.824899Z","steps":["trace[188113818] 'read index received' (duration: 115.961459ms)","trace[188113818] 'applied index is now lower than readState.Index' (duration: 126.024079ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:32:33.82531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.406655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:32:33.825409Z","caller":"traceutil/trace.go:171","msg":"trace[1529627219] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:8833; }","duration":"185.568989ms","start":"2026-07-08T02:32:33.639815Z","end":"2026-07-08T02:32:33.825384Z","steps":["trace[1529627219] 'agreement among raft nodes before linearized reading' (duration: 185.386774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:32:33.825413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.448441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:32:33.825396Z","caller":"traceutil/trace.go:171","msg":"trace[1772472354] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"255.664854ms","start":"2026-07-08T02:32:33.569642Z","end":"2026-07-08T02:32:33.825307Z","steps":["trace[1772472354] 'process raft request' (duration: 129.297625ms)","trace[1772472354] 'compare' (duration: 125.477843ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:32:33.825525Z","caller":"traceutil/trace.go:171","msg":"trace[319144606] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8833; }","duration":"242.619886ms","start":"2026-07-08T02:32:33.582882Z","end":"2026-07-08T02:32:33.825502Z","steps":["trace[319144606] 'agreement among raft nodes before linearized reading' (duration: 242.374929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:32:34.821219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.16439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:32:34.821324Z","caller":"traceutil/trace.go:171","msg":"trace[117283510] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:8839; }","duration":"217.282073ms","start":"2026-07-08T02:32:34.604012Z","end":"2026-07-08T02:32:34.821294Z","steps":["trace[117283510] 'count revisions from in-memory index tree' (duration: 217.078468ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:33:06.582218Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002515.wal"} {"level":"info","ts":"2026-07-08T02:33:52.497331Z","caller":"traceutil/trace.go:171","msg":"trace[1999077461] linearizableReadLoop","detail":"{readStateIndex:9938; appliedIndex:9937; }","duration":"206.145946ms","start":"2026-07-08T02:33:52.291158Z","end":"2026-07-08T02:33:52.497304Z","steps":["trace[1999077461] 'read index received' (duration: 205.962221ms)","trace[1999077461] 'applied index is now lower than readState.Index' (duration: 182.495µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:33:52.497451Z","caller":"traceutil/trace.go:171","msg":"trace[1158504597] transaction","detail":"{read_only:false; response_revision:9595; number_of_response:1; }","duration":"237.369176ms","start":"2026-07-08T02:33:52.260052Z","end":"2026-07-08T02:33:52.497422Z","steps":["trace[1158504597] 'process raft request' (duration: 237.069478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:33:52.497491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.3102ms","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:5"} {"level":"info","ts":"2026-07-08T02:33:52.497528Z","caller":"traceutil/trace.go:171","msg":"trace[1233972118] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:9595; }","duration":"206.419773ms","start":"2026-07-08T02:33:52.291098Z","end":"2026-07-08T02:33:52.497518Z","steps":["trace[1233972118] 'agreement among raft nodes before linearized reading' (duration: 206.31394ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:33:59.203082Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T02:33:59.209876Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-08T02:33:59.210092Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-08T02:36:03.995635Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8082} {"level":"info","ts":"2026-07-08T02:36:04.076018Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8082,"took":"77.246839ms","hash":1406264030,"current-db-size-bytes":57352192,"current-db-size":"57 MB","current-db-size-in-use-bytes":28499968,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-08T02:36:04.076078Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1406264030,"revision":8082,"compact-revision":5999} {"level":"info","ts":"2026-07-08T02:41:04.00082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10494} {"level":"info","ts":"2026-07-08T02:41:04.079102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10494,"took":"75.613171ms","hash":1896679999,"current-db-size-bytes":57352192,"current-db-size":"57 MB","current-db-size-in-use-bytes":26386432,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-08T02:41:04.079159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1896679999,"revision":10494,"compact-revision":8082} {"level":"info","ts":"2026-07-08T02:46:04.009237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12650} {"level":"info","ts":"2026-07-08T02:46:04.086939Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12650,"took":"75.475836ms","hash":986001003,"current-db-size-bytes":57352192,"current-db-size":"57 MB","current-db-size-in-use-bytes":27049984,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-08T02:46:04.087011Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":986001003,"revision":12650,"compact-revision":10494} {"level":"warn","ts":"2026-07-08T02:46:06.963927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.991844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:46:06.964037Z","caller":"traceutil/trace.go:171","msg":"trace[812152576] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:14673; }","duration":"141.194131ms","start":"2026-07-08T02:46:06.82282Z","end":"2026-07-08T02:46:06.964014Z","steps":["trace[812152576] 'count revisions from in-memory index tree' (duration: 140.844731ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:46:06.964094Z","caller":"traceutil/trace.go:171","msg":"trace[8316584] transaction","detail":"{read_only:false; response_revision:14674; number_of_response:1; }","duration":"111.583927ms","start":"2026-07-08T02:46:06.852466Z","end":"2026-07-08T02:46:06.96405Z","steps":["trace[8316584] 'process raft request' (duration: 104.219755ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:46:07.742597Z","caller":"traceutil/trace.go:171","msg":"trace[267959073] linearizableReadLoop","detail":"{readStateIndex:15195; appliedIndex:15194; }","duration":"155.871754ms","start":"2026-07-08T02:46:07.586701Z","end":"2026-07-08T02:46:07.742572Z","steps":["trace[267959073] 'read index received' (duration: 155.575566ms)","trace[267959073] 'applied index is now lower than readState.Index' (duration: 295.418µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:46:07.742648Z","caller":"traceutil/trace.go:171","msg":"trace[912533908] transaction","detail":"{read_only:false; response_revision:14677; number_of_response:1; }","duration":"265.566087ms","start":"2026-07-08T02:46:07.477049Z","end":"2026-07-08T02:46:07.742615Z","steps":["trace[912533908] 'process raft request' (duration: 265.339041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:46:07.742781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.063689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-08T02:46:07.742813Z","caller":"traceutil/trace.go:171","msg":"trace[1473624113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14677; }","duration":"156.154722ms","start":"2026-07-08T02:46:07.58665Z","end":"2026-07-08T02:46:07.742805Z","steps":["trace[1473624113] 'agreement among raft nodes before linearized reading' (duration: 156.022848ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:46:07.919874Z","caller":"traceutil/trace.go:171","msg":"trace[1479381100] linearizableReadLoop","detail":"{readStateIndex:15196; appliedIndex:15195; }","duration":"110.637989ms","start":"2026-07-08T02:46:07.809209Z","end":"2026-07-08T02:46:07.919847Z","steps":["trace[1479381100] 'read index received' (duration: 99.384925ms)","trace[1479381100] 'applied index is now lower than readState.Index' (duration: 11.252224ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:46:07.92007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.841635ms","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-08T02:46:07.920037Z","caller":"traceutil/trace.go:171","msg":"trace[846052444] transaction","detail":"{read_only:false; response_revision:14678; number_of_response:1; }","duration":"172.932146ms","start":"2026-07-08T02:46:07.74706Z","end":"2026-07-08T02:46:07.919992Z","steps":["trace[846052444] 'process raft request' (duration: 161.524336ms)","trace[846052444] 'compare' (duration: 11.09895ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:46:07.920111Z","caller":"traceutil/trace.go:171","msg":"trace[149729120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14678; }","duration":"110.927979ms","start":"2026-07-08T02:46:07.809167Z","end":"2026-07-08T02:46:07.920095Z","steps":["trace[149729120] 'agreement among raft nodes before linearized reading' (duration: 110.775514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:46:32.448957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.678428ms","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-08T02:46:32.449066Z","caller":"traceutil/trace.go:171","msg":"trace[1329789064] 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:14998; }","duration":"155.859984ms","start":"2026-07-08T02:46:32.293186Z","end":"2026-07-08T02:46:32.449046Z","steps":["trace[1329789064] 'count revisions from in-memory index tree' (duration: 155.531844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:46:32.449069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.384569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-842bbbaf-9756-4e27-99db-f718e41fdb84\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-07-08T02:46:32.449208Z","caller":"traceutil/trace.go:171","msg":"trace[1957415754] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-842bbbaf-9756-4e27-99db-f718e41fdb84; range_end:; response_count:1; response_revision:14998; }","duration":"139.559865ms","start":"2026-07-08T02:46:32.309625Z","end":"2026-07-08T02:46:32.449184Z","steps":["trace[1957415754] 'range keys from in-memory index tree' (duration: 139.229864ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:46:32.525871Z","caller":"traceutil/trace.go:171","msg":"trace[1538518003] transaction","detail":"{read_only:false; response_revision:14999; number_of_response:1; }","duration":"157.266774ms","start":"2026-07-08T02:46:32.368567Z","end":"2026-07-08T02:46:32.525834Z","steps":["trace[1538518003] 'process raft request' (duration: 157.042918ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:46:32.589302Z","caller":"traceutil/trace.go:171","msg":"trace[1462037154] transaction","detail":"{read_only:false; response_revision:15000; number_of_response:1; }","duration":"155.989598ms","start":"2026-07-08T02:46:32.433254Z","end":"2026-07-08T02:46:32.589244Z","steps":["trace[1462037154] 'process raft request' (duration: 120.322689ms)","trace[1462037154] 'compare' (duration: 35.358939ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:46:32.589485Z","caller":"traceutil/trace.go:171","msg":"trace[1553506783] linearizableReadLoop","detail":"{readStateIndex:15532; appliedIndex:15530; }","duration":"128.795013ms","start":"2026-07-08T02:46:32.460663Z","end":"2026-07-08T02:46:32.589458Z","steps":["trace[1553506783] 'read index received' (duration: 65.94371ms)","trace[1553506783] 'applied index is now lower than readState.Index' (duration: 62.850243ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:46:32.589728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.697017ms","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:5"} {"level":"info","ts":"2026-07-08T02:46:32.58984Z","caller":"traceutil/trace.go:171","msg":"trace[1285640180] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:15002; }","duration":"114.848381ms","start":"2026-07-08T02:46:32.474972Z","end":"2026-07-08T02:46:32.58982Z","steps":["trace[1285640180] 'agreement among raft nodes before linearized reading' (duration: 114.699097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:46:32.590076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.3745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-08T02:46:32.590189Z","caller":"traceutil/trace.go:171","msg":"trace[1275081028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15002; }","duration":"129.544175ms","start":"2026-07-08T02:46:32.460616Z","end":"2026-07-08T02:46:32.59016Z","steps":["trace[1275081028] 'agreement among raft nodes before linearized reading' (duration: 129.112623ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:46:32.590217Z","caller":"traceutil/trace.go:171","msg":"trace[240947424] transaction","detail":"{read_only:false; response_revision:15001; number_of_response:1; }","duration":"131.901443ms","start":"2026-07-08T02:46:32.457725Z","end":"2026-07-08T02:46:32.589626Z","steps":["trace[240947424] 'process raft request' (duration: 131.347607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:24.261416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.278896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-07-08T02:47:24.261451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.662908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:47:24.261539Z","caller":"traceutil/trace.go:171","msg":"trace[310603811] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15626; }","duration":"122.441711ms","start":"2026-07-08T02:47:24.139074Z","end":"2026-07-08T02:47:24.261515Z","steps":["trace[310603811] 'range keys from in-memory index tree' (duration: 122.134672ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:24.261558Z","caller":"traceutil/trace.go:171","msg":"trace[1566418534] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15626; }","duration":"122.855423ms","start":"2026-07-08T02:47:24.138682Z","end":"2026-07-08T02:47:24.261538Z","steps":["trace[1566418534] 'range keys from in-memory index tree' (duration: 122.504933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:24.261528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.288732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-08T02:47:24.26162Z","caller":"traceutil/trace.go:171","msg":"trace[1694374778] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15626; }","duration":"117.395876ms","start":"2026-07-08T02:47:24.144202Z","end":"2026-07-08T02:47:24.261598Z","steps":["trace[1694374778] 'range keys from in-memory index tree' (duration: 117.114097ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:58.25424Z","caller":"traceutil/trace.go:171","msg":"trace[1920420777] transaction","detail":"{read_only:false; response_revision:15808; number_of_response:1; }","duration":"102.055962ms","start":"2026-07-08T02:47:58.152134Z","end":"2026-07-08T02:47:58.25419Z","steps":["trace[1920420777] 'process raft request' (duration: 101.747953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:58.791085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.268229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:47:58.791215Z","caller":"traceutil/trace.go:171","msg":"trace[222951736] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:15811; }","duration":"119.487325ms","start":"2026-07-08T02:47:58.671698Z","end":"2026-07-08T02:47:58.791186Z","steps":["trace[222951736] 'count revisions from in-memory index tree' (duration: 119.097164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:58.791354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.659349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-07-08T02:47:58.791025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.202475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:47:58.791471Z","caller":"traceutil/trace.go:171","msg":"trace[1853249310] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15811; }","duration":"104.814803ms","start":"2026-07-08T02:47:58.686629Z","end":"2026-07-08T02:47:58.791444Z","steps":["trace[1853249310] 'range keys from in-memory index tree' (duration: 104.394091ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:58.791504Z","caller":"traceutil/trace.go:171","msg":"trace[1847132931] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15811; }","duration":"104.699719ms","start":"2026-07-08T02:47:58.686767Z","end":"2026-07-08T02:47:58.791466Z","steps":["trace[1847132931] 'range keys from in-memory index tree' (duration: 104.057162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:58.791591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.007511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-08T02:47:58.791742Z","caller":"traceutil/trace.go:171","msg":"trace[1921271399] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15811; }","duration":"103.225766ms","start":"2026-07-08T02:47:58.688485Z","end":"2026-07-08T02:47:58.79171Z","steps":["trace[1921271399] 'range keys from in-memory index tree' (duration: 102.716272ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:51:04.014588Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14644} {"level":"info","ts":"2026-07-08T02:51:04.094661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14644,"took":"77.925182ms","hash":3831981678,"current-db-size-bytes":57352192,"current-db-size":"57 MB","current-db-size-in-use-bytes":34144256,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-08T02:51:04.094733Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3831981678,"revision":14644,"compact-revision":12650} {"level":"info","ts":"2026-07-08T02:53:49.979624Z","caller":"traceutil/trace.go:171","msg":"trace[1642474944] linearizableReadLoop","detail":"{readStateIndex:19051; appliedIndex:19050; }","duration":"109.050156ms","start":"2026-07-08T02:53:49.870545Z","end":"2026-07-08T02:53:49.979595Z","steps":["trace[1642474944] 'read index received' (duration: 95.596898ms)","trace[1642474944] 'applied index is now lower than readState.Index' (duration: 13.452168ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:53:49.979656Z","caller":"traceutil/trace.go:171","msg":"trace[1709784740] transaction","detail":"{read_only:false; response_revision:18413; number_of_response:1; }","duration":"131.264358ms","start":"2026-07-08T02:53:49.848373Z","end":"2026-07-08T02:53:49.979638Z","steps":["trace[1709784740] 'process raft request' (duration: 117.922132ms)","trace[1709784740] 'compare' (duration: 13.118779ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:53:49.979741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.183801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T02:53:49.979813Z","caller":"traceutil/trace.go:171","msg":"trace[1573417765] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:18413; }","duration":"109.306684ms","start":"2026-07-08T02:53:49.870486Z","end":"2026-07-08T02:53:49.979793Z","steps":["trace[1573417765] 'agreement among raft nodes before linearized reading' (duration: 109.191661ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:53:50.248078Z","caller":"traceutil/trace.go:171","msg":"trace[1678892275] transaction","detail":"{read_only:false; response_revision:18414; number_of_response:1; }","duration":"148.152955ms","start":"2026-07-08T02:53:50.099903Z","end":"2026-07-08T02:53:50.248056Z","steps":["trace[1678892275] 'process raft request' (duration: 147.664811ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:53:50.331554Z","caller":"traceutil/trace.go:171","msg":"trace[1460400429] transaction","detail":"{read_only:false; response_revision:18415; number_of_response:1; }","duration":"108.274345ms","start":"2026-07-08T02:53:50.223258Z","end":"2026-07-08T02:53:50.331533Z","steps":["trace[1460400429] 'process raft request' (duration: 89.245596ms)","trace[1460400429] 'compare' (duration: 18.933095ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:53:50.785373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.83079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T02:53:50.785455Z","caller":"traceutil/trace.go:171","msg":"trace[523145349] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:18417; }","duration":"231.974754ms","start":"2026-07-08T02:53:50.553461Z","end":"2026-07-08T02:53:50.785436Z","steps":["trace[523145349] 'count revisions from in-memory index tree' (duration: 231.742687ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:55:17.801176Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T02:55:17.806329Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-08T02:55:17.806457Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-08T02:56:04.022874Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17330} {"level":"info","ts":"2026-07-08T02:56:04.116342Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17330,"took":"89.912544ms","hash":4067621882,"current-db-size-bytes":57352192,"current-db-size":"57 MB","current-db-size-in-use-bytes":35020800,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-08T02:56:04.116405Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4067621882,"revision":17330,"compact-revision":14644} {"level":"info","ts":"2026-07-08T02:59:31.000443Z","caller":"traceutil/trace.go:171","msg":"trace[1765954557] transaction","detail":"{read_only:false; response_revision:21071; number_of_response:1; }","duration":"118.741316ms","start":"2026-07-08T02:59:30.881677Z","end":"2026-07-08T02:59:31.000419Z","steps":["trace[1765954557] 'process raft request' (duration: 118.585922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:37.12573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.381569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T02:59:37.125897Z","caller":"traceutil/trace.go:171","msg":"trace[1605917988] transaction","detail":"{read_only:false; response_revision:21098; number_of_response:1; }","duration":"377.052706ms","start":"2026-07-08T02:59:36.748824Z","end":"2026-07-08T02:59:37.125877Z","steps":["trace[1605917988] 'process raft request' (duration: 183.429621ms)","trace[1605917988] 'compare' (duration: 193.027088ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:59:37.125962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:36.748803Z","time spent":"377.128228ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:59:37.50384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.352126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T02:59:37.504139Z","caller":"traceutil/trace.go:171","msg":"trace[1863919576] linearizableReadLoop","detail":"{readStateIndex:21826; appliedIndex:21824; }","duration":"566.008768ms","start":"2026-07-08T02:59:36.938108Z","end":"2026-07-08T02:59:37.504117Z","steps":["trace[1863919576] 'read index received' (duration: 345.379113ms)","trace[1863919576] 'applied index is now lower than readState.Index' (duration: 220.628255ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:59:37.504283Z","caller":"traceutil/trace.go:171","msg":"trace[1315711226] transaction","detail":"{read_only:false; response_revision:21099; number_of_response:1; }","duration":"755.231815ms","start":"2026-07-08T02:59:36.749034Z","end":"2026-07-08T02:59:37.504266Z","steps":["trace[1315711226] 'process raft request' (duration: 534.332323ms)","trace[1315711226] 'compare' (duration: 220.199152ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:59:37.504271Z","caller":"traceutil/trace.go:171","msg":"trace[1347972601] transaction","detail":"{read_only:false; response_revision:21100; number_of_response:1; }","duration":"738.38247ms","start":"2026-07-08T02:59:36.765789Z","end":"2026-07-08T02:59:37.504172Z","steps":["trace[1347972601] 'process raft request' (duration: 738.181244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:37.504388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:36.749006Z","time spent":"755.333118ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:59:37.504535Z","caller":"traceutil/trace.go:171","msg":"trace[914114006] transaction","detail":"{read_only:false; response_revision:21101; number_of_response:1; }","duration":"472.565021ms","start":"2026-07-08T02:59:37.031765Z","end":"2026-07-08T02:59:37.50433Z","steps":["trace[914114006] 'process raft request' (duration: 472.296513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:37.504594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:36.765704Z","time spent":"738.658457ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:59:37.504716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.581894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"warn","ts":"2026-07-08T02:59:37.504807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:37.031747Z","time spent":"472.85713ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:59:37.504859Z","caller":"traceutil/trace.go:171","msg":"trace[1964380090] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:21101; }","duration":"566.754209ms","start":"2026-07-08T02:59:36.938058Z","end":"2026-07-08T02:59:37.504812Z","steps":["trace[1964380090] 'agreement among raft nodes before linearized reading' (duration: 566.168942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:37.504934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:36.938038Z","time spent":"566.880124ms","remote":"127.0.0.1:42978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1886,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"warn","ts":"2026-07-08T02:59:37.515222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.897394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T02:59:37.515282Z","caller":"traceutil/trace.go:171","msg":"trace[1234624160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21102; }","duration":"496.999197ms","start":"2026-07-08T02:59:37.018268Z","end":"2026-07-08T02:59:37.515267Z","steps":["trace[1234624160] 'agreement among raft nodes before linearized reading' (duration: 496.894534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:37.51531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:37.018248Z","time spent":"497.055608ms","remote":"127.0.0.1:42818","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-08T02:59:37.515257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.213807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-08T02:59:37.515396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.111259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-08T02:59:37.51545Z","caller":"traceutil/trace.go:171","msg":"trace[400489999] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:21102; }","duration":"281.198381ms","start":"2026-07-08T02:59:37.234237Z","end":"2026-07-08T02:59:37.515435Z","steps":["trace[400489999] 'agreement among raft nodes before linearized reading' (duration: 281.054617ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:59:37.515505Z","caller":"traceutil/trace.go:171","msg":"trace[1537156805] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:21102; }","duration":"329.430612ms","start":"2026-07-08T02:59:37.18599Z","end":"2026-07-08T02:59:37.515421Z","steps":["trace[1537156805] 'agreement among raft nodes before linearized reading' (duration: 329.175615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:37.515588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:37.185974Z","time spent":"329.595657ms","remote":"127.0.0.1:42934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":13,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-08T02:59:37.866874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.029735ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-08T02:59:37.867004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:37.516584Z","time spent":"350.413578ms","remote":"127.0.0.1:42866","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-08T02:59:37.867084Z","caller":"traceutil/trace.go:171","msg":"trace[422205272] transaction","detail":"{read_only:false; response_revision:21103; number_of_response:1; }","duration":"248.140058ms","start":"2026-07-08T02:59:37.618929Z","end":"2026-07-08T02:59:37.867069Z","steps":["trace[422205272] 'process raft request' (duration: 248.038215ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:59:37.867275Z","caller":"traceutil/trace.go:171","msg":"trace[953440900] linearizableReadLoop","detail":"{readStateIndex:21829; appliedIndex:21828; }","duration":"350.231983ms","start":"2026-07-08T02:59:37.517031Z","end":"2026-07-08T02:59:37.867263Z","steps":["trace[953440900] 'read index received' (duration: 179.819697ms)","trace[953440900] 'applied index is now lower than readState.Index' (duration: 170.411256ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:59:37.86735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.312595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T02:59:37.867367Z","caller":"traceutil/trace.go:171","msg":"trace[346534516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21103; }","duration":"350.354726ms","start":"2026-07-08T02:59:37.517006Z","end":"2026-07-08T02:59:37.867361Z","steps":["trace[346534516] 'agreement among raft nodes before linearized reading' (duration: 350.289525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:37.867385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:37.516993Z","time spent":"350.388097ms","remote":"127.0.0.1:42834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T02:59:38.294603Z","caller":"traceutil/trace.go:171","msg":"trace[1060843833] linearizableReadLoop","detail":"{readStateIndex:21832; appliedIndex:21831; }","duration":"140.329068ms","start":"2026-07-08T02:59:38.154249Z","end":"2026-07-08T02:59:38.294578Z","steps":["trace[1060843833] 'read index received' (duration: 140.160833ms)","trace[1060843833] 'applied index is now lower than readState.Index' (duration: 166.795µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:59:38.294689Z","caller":"traceutil/trace.go:171","msg":"trace[176951958] transaction","detail":"{read_only:false; response_revision:21105; number_of_response:1; }","duration":"251.164475ms","start":"2026-07-08T02:59:38.043487Z","end":"2026-07-08T02:59:38.294652Z","steps":["trace[176951958] 'process raft request' (duration: 250.912828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:38.294841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.571294ms","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-08T02:59:38.294906Z","caller":"traceutil/trace.go:171","msg":"trace[1499150406] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21105; }","duration":"140.687789ms","start":"2026-07-08T02:59:38.154198Z","end":"2026-07-08T02:59:38.294886Z","steps":["trace[1499150406] 'agreement among raft nodes before linearized reading' (duration: 140.487743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:38.637003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.429888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T02:59:38.637074Z","caller":"traceutil/trace.go:171","msg":"trace[1347926630] linearizableReadLoop","detail":"{readStateIndex:21833; appliedIndex:21832; }","duration":"247.968363ms","start":"2026-07-08T02:59:38.389095Z","end":"2026-07-08T02:59:38.637064Z","steps":["trace[1347926630] 'read index received' (duration: 78.422402ms)","trace[1347926630] 'applied index is now lower than readState.Index' (duration: 169.545231ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:59:38.637193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.109318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-api-7bfdd56dcf-s2sms.18c0311be947b59a\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-07-08T02:59:38.637214Z","caller":"traceutil/trace.go:171","msg":"trace[311400017] range","detail":"{range_begin:/registry/events/openstack/heat-api-7bfdd56dcf-s2sms.18c0311be947b59a; range_end:; response_count:1; response_revision:21106; }","duration":"248.168159ms","start":"2026-07-08T02:59:38.38904Z","end":"2026-07-08T02:59:38.637208Z","steps":["trace[311400017] 'agreement among raft nodes before linearized reading' (duration: 248.077056ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:59:38.637176Z","caller":"traceutil/trace.go:171","msg":"trace[857717116] transaction","detail":"{read_only:false; response_revision:21106; number_of_response:1; }","duration":"337.549087ms","start":"2026-07-08T02:59:38.299567Z","end":"2026-07-08T02:59:38.637116Z","steps":["trace[857717116] 'process raft request' (duration: 167.949845ms)","trace[857717116] 'compare' (duration: 169.360456ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:59:38.637288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.041369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-08T02:59:38.637349Z","caller":"traceutil/trace.go:171","msg":"trace[1626487635] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:21106; }","duration":"194.142551ms","start":"2026-07-08T02:59:38.443195Z","end":"2026-07-08T02:59:38.637337Z","steps":["trace[1626487635] 'agreement among raft nodes before linearized reading' (duration: 193.948915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:59:38.637652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:59:38.299543Z","time spent":"337.717533ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:01:04.030197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19662} {"level":"info","ts":"2026-07-08T03:01:04.15042Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19662,"took":"116.446579ms","hash":1684614436,"current-db-size-bytes":57352192,"current-db-size":"57 MB","current-db-size-in-use-bytes":30281728,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-08T03:01:04.150481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1684614436,"revision":19662,"compact-revision":17330} {"level":"info","ts":"2026-07-08T03:06:04.036005Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21973} {"level":"info","ts":"2026-07-08T03:06:04.146859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21973,"took":"106.905257ms","hash":474732898,"current-db-size-bytes":57352192,"current-db-size":"57 MB","current-db-size-in-use-bytes":45342720,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-07-08T03:06:04.146961Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":474732898,"revision":21973,"compact-revision":19662} {"level":"info","ts":"2026-07-08T03:07:01.290247Z","caller":"traceutil/trace.go:171","msg":"trace[984652488] linearizableReadLoop","detail":"{readStateIndex:26651; appliedIndex:26650; }","duration":"113.627082ms","start":"2026-07-08T03:07:01.176598Z","end":"2026-07-08T03:07:01.290225Z","steps":["trace[984652488] 'read index received' (duration: 88.183718ms)","trace[984652488] 'applied index is now lower than readState.Index' (duration: 25.442234ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:01.290388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.777706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T03:07:01.290411Z","caller":"traceutil/trace.go:171","msg":"trace[313193684] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:25794; }","duration":"113.860688ms","start":"2026-07-08T03:07:01.176541Z","end":"2026-07-08T03:07:01.290402Z","steps":["trace[313193684] 'agreement among raft nodes before linearized reading' (duration: 113.792656ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:01.290782Z","caller":"traceutil/trace.go:171","msg":"trace[584301760] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"126.177143ms","start":"2026-07-08T03:07:01.164516Z","end":"2026-07-08T03:07:01.290694Z","steps":["trace[584301760] 'process raft request' (duration: 100.183272ms)","trace[584301760] 'compare' (duration: 25.415675ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:01.65748Z","caller":"traceutil/trace.go:171","msg":"trace[538696666] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"108.889964ms","start":"2026-07-08T03:07:01.547733Z","end":"2026-07-08T03:07:01.656623Z","steps":["trace[538696666] 'process raft request' (duration: 108.713699ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:06.436377Z","caller":"traceutil/trace.go:171","msg":"trace[178097082] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"136.04809ms","start":"2026-07-08T03:07:06.300299Z","end":"2026-07-08T03:07:06.436347Z","steps":["trace[178097082] 'process raft request' (duration: 135.75198ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:06.738396Z","caller":"traceutil/trace.go:171","msg":"trace[2096396709] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"122.513437ms","start":"2026-07-08T03:07:06.615849Z","end":"2026-07-08T03:07:06.738362Z","steps":["trace[2096396709] 'process raft request' (duration: 122.333672ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:07.21933Z","caller":"traceutil/trace.go:171","msg":"trace[859068549] transaction","detail":"{read_only:false; response_revision:25842; number_of_response:1; }","duration":"116.490255ms","start":"2026-07-08T03:07:07.102803Z","end":"2026-07-08T03:07:07.219293Z","steps":["trace[859068549] 'process raft request' (duration: 116.157695ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:07.510435Z","caller":"traceutil/trace.go:171","msg":"trace[1371061999] linearizableReadLoop","detail":"{readStateIndex:26701; appliedIndex:26700; }","duration":"253.549654ms","start":"2026-07-08T03:07:07.256834Z","end":"2026-07-08T03:07:07.510384Z","steps":["trace[1371061999] 'read index received' (duration: 221.91375ms)","trace[1371061999] 'applied index is now lower than readState.Index' (duration: 31.634884ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:07.510741Z","caller":"traceutil/trace.go:171","msg":"trace[1213418463] transaction","detail":"{read_only:false; response_revision:25843; number_of_response:1; }","duration":"286.941146ms","start":"2026-07-08T03:07:07.223748Z","end":"2026-07-08T03:07:07.510689Z","steps":["trace[1213418463] 'process raft request' (duration: 254.983094ms)","trace[1213418463] 'compare' (duration: 31.489649ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:07.510935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.001796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-08T03:07:07.51113Z","caller":"traceutil/trace.go:171","msg":"trace[1861196148] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:25843; }","duration":"254.175001ms","start":"2026-07-08T03:07:07.256827Z","end":"2026-07-08T03:07:07.511002Z","steps":["trace[1861196148] 'agreement among raft nodes before linearized reading' (duration: 253.706958ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:07.557302Z","caller":"traceutil/trace.go:171","msg":"trace[1177393196] transaction","detail":"{read_only:false; response_revision:25844; number_of_response:1; }","duration":"245.183662ms","start":"2026-07-08T03:07:07.312083Z","end":"2026-07-08T03:07:07.557266Z","steps":["trace[1177393196] 'process raft request' (duration: 244.973886ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:07.557626Z","caller":"traceutil/trace.go:171","msg":"trace[1084344399] transaction","detail":"{read_only:false; response_revision:25846; number_of_response:1; }","duration":"240.311231ms","start":"2026-07-08T03:07:07.3173Z","end":"2026-07-08T03:07:07.557611Z","steps":["trace[1084344399] 'process raft request' (duration: 240.167756ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:07.557626Z","caller":"traceutil/trace.go:171","msg":"trace[1990352503] transaction","detail":"{read_only:false; response_revision:25845; number_of_response:1; }","duration":"240.461994ms","start":"2026-07-08T03:07:07.317139Z","end":"2026-07-08T03:07:07.557601Z","steps":["trace[1990352503] 'process raft request' (duration: 240.061093ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:07.557822Z","caller":"traceutil/trace.go:171","msg":"trace[679646972] transaction","detail":"{read_only:false; response_revision:25847; number_of_response:1; }","duration":"239.723674ms","start":"2026-07-08T03:07:07.318029Z","end":"2026-07-08T03:07:07.557753Z","steps":["trace[679646972] 'process raft request' (duration: 239.541918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:07.749467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.468906ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-08T03:07:07.79118Z","caller":"traceutil/trace.go:171","msg":"trace[2088483153] transaction","detail":"{read_only:false; response_revision:25850; number_of_response:1; }","duration":"105.128586ms","start":"2026-07-08T03:07:07.686028Z","end":"2026-07-08T03:07:07.791156Z","steps":["trace[2088483153] 'process raft request' (duration: 104.780556ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:07.904229Z","caller":"traceutil/trace.go:171","msg":"trace[64552139] transaction","detail":"{read_only:false; response_revision:25852; number_of_response:1; }","duration":"150.174958ms","start":"2026-07-08T03:07:07.754033Z","end":"2026-07-08T03:07:07.904208Z","steps":["trace[64552139] 'process raft request' (duration: 150.098415ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:07.904249Z","caller":"traceutil/trace.go:171","msg":"trace[2003521522] transaction","detail":"{read_only:false; response_revision:25851; number_of_response:1; }","duration":"152.630939ms","start":"2026-07-08T03:07:07.751574Z","end":"2026-07-08T03:07:07.904205Z","steps":["trace[2003521522] 'process raft request' (duration: 133.752713ms)","trace[2003521522] 'compare' (duration: 18.673789ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:08.235092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.610534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-08T03:07:08.2351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.334727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:07:08.235174Z","caller":"traceutil/trace.go:171","msg":"trace[2126330001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25852; }","duration":"138.740897ms","start":"2026-07-08T03:07:08.096415Z","end":"2026-07-08T03:07:08.235156Z","steps":["trace[2126330001] 'range keys from in-memory index tree' (duration: 138.429098ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:08.235197Z","caller":"traceutil/trace.go:171","msg":"trace[1898458684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25852; }","duration":"217.471151ms","start":"2026-07-08T03:07:08.017706Z","end":"2026-07-08T03:07:08.235177Z","steps":["trace[1898458684] 'range keys from in-memory index tree' (duration: 217.186733ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:08.374365Z","caller":"traceutil/trace.go:171","msg":"trace[943309515] linearizableReadLoop","detail":"{readStateIndex:26712; appliedIndex:26711; }","duration":"102.726326ms","start":"2026-07-08T03:07:08.271612Z","end":"2026-07-08T03:07:08.374338Z","steps":["trace[943309515] 'read index received' (duration: 102.558642ms)","trace[943309515] 'applied index is now lower than readState.Index' (duration: 166.784µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:08.374509Z","caller":"traceutil/trace.go:171","msg":"trace[338805173] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"135.231305ms","start":"2026-07-08T03:07:08.239249Z","end":"2026-07-08T03:07:08.37448Z","steps":["trace[338805173] 'process raft request' (duration: 134.914686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:08.374524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.932212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:07:08.37458Z","caller":"traceutil/trace.go:171","msg":"trace[1125839882] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25853; }","duration":"103.081036ms","start":"2026-07-08T03:07:08.271484Z","end":"2026-07-08T03:07:08.374565Z","steps":["trace[1125839882] 'agreement among raft nodes before linearized reading' (duration: 102.970843ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:09.066205Z","caller":"traceutil/trace.go:171","msg":"trace[1632938451] transaction","detail":"{read_only:false; response_revision:25854; number_of_response:1; }","duration":"318.816416ms","start":"2026-07-08T03:07:08.747367Z","end":"2026-07-08T03:07:09.066184Z","steps":["trace[1632938451] 'process raft request' (duration: 318.679542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:09.066333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:08.747347Z","time spent":"318.917179ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:09.231006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.781437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T03:07:09.231127Z","caller":"traceutil/trace.go:171","msg":"trace[505229171] linearizableReadLoop","detail":"{readStateIndex:26714; appliedIndex:26712; }","duration":"199.001017ms","start":"2026-07-08T03:07:09.032105Z","end":"2026-07-08T03:07:09.231106Z","steps":["trace[505229171] 'read index received' (duration: 33.964011ms)","trace[505229171] 'applied index is now lower than readState.Index' (duration: 165.035646ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:09.23117Z","caller":"traceutil/trace.go:171","msg":"trace[2103202883] transaction","detail":"{read_only:false; response_revision:25855; number_of_response:1; }","duration":"477.120159ms","start":"2026-07-08T03:07:08.754017Z","end":"2026-07-08T03:07:09.231137Z","steps":["trace[2103202883] 'process raft request' (duration: 337.121606ms)","trace[2103202883] 'compare' (duration: 139.641943ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:09.231307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.202443ms","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-08T03:07:09.231348Z","caller":"traceutil/trace.go:171","msg":"trace[1583550441] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25855; }","duration":"199.284475ms","start":"2026-07-08T03:07:09.032055Z","end":"2026-07-08T03:07:09.231339Z","steps":["trace[1583550441] 'agreement among raft nodes before linearized reading' (duration: 199.155241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:09.231354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:08.754Z","time spent":"477.259373ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:09.536308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.517932ms","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-08T03:07:09.536428Z","caller":"traceutil/trace.go:171","msg":"trace[1859489425] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:25856; }","duration":"199.683027ms","start":"2026-07-08T03:07:09.33673Z","end":"2026-07-08T03:07:09.536413Z","steps":["trace[1859489425] 'count revisions from in-memory index tree' (duration: 199.414508ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:09.876529Z","caller":"traceutil/trace.go:171","msg":"trace[123933269] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"314.568556ms","start":"2026-07-08T03:07:09.561935Z","end":"2026-07-08T03:07:09.876503Z","steps":["trace[123933269] 'process raft request' (duration: 290.686426ms)","trace[123933269] 'compare' (duration: 22.884591ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:09.876727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:09.561905Z","time spent":"314.75267ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:09.87704Z","caller":"traceutil/trace.go:171","msg":"trace[213139943] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"314.05661ms","start":"2026-07-08T03:07:09.562821Z","end":"2026-07-08T03:07:09.876878Z","steps":["trace[213139943] 'process raft request' (duration: 313.594937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:09.877136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:09.562793Z","time spent":"314.311877ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:09.877206Z","caller":"traceutil/trace.go:171","msg":"trace[1808225868] transaction","detail":"{read_only:false; response_revision:25860; number_of_response:1; }","duration":"313.808073ms","start":"2026-07-08T03:07:09.563391Z","end":"2026-07-08T03:07:09.877199Z","steps":["trace[1808225868] 'process raft request' (duration: 313.081382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:09.877244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:09.563375Z","time spent":"313.849534ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:09.877311Z","caller":"traceutil/trace.go:171","msg":"trace[302616496] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"313.789832ms","start":"2026-07-08T03:07:09.563499Z","end":"2026-07-08T03:07:09.877288Z","steps":["trace[302616496] 'process raft request' (duration: 313.00595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:09.877381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:09.563484Z","time spent":"313.857604ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:09.877495Z","caller":"traceutil/trace.go:171","msg":"trace[1691246916] transaction","detail":"{read_only:false; response_revision:25862; number_of_response:1; }","duration":"313.493514ms","start":"2026-07-08T03:07:09.56398Z","end":"2026-07-08T03:07:09.877473Z","steps":["trace[1691246916] 'process raft request' (duration: 312.549717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:09.877561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:09.563965Z","time spent":"313.566115ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:09.877831Z","caller":"traceutil/trace.go:171","msg":"trace[1734276824] transaction","detail":"{read_only:false; response_revision:25863; number_of_response:1; }","duration":"122.998983ms","start":"2026-07-08T03:07:09.754821Z","end":"2026-07-08T03:07:09.87782Z","steps":["trace[1734276824] 'process raft request' (duration: 121.732416ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:10.247345Z","caller":"traceutil/trace.go:171","msg":"trace[744576990] linearizableReadLoop","detail":"{readStateIndex:26724; appliedIndex:26723; }","duration":"227.806689ms","start":"2026-07-08T03:07:10.019505Z","end":"2026-07-08T03:07:10.247312Z","steps":["trace[744576990] 'read index received' (duration: 207.160343ms)","trace[744576990] 'applied index is now lower than readState.Index' (duration: 20.644786ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:10.247549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.020045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T03:07:10.247577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.202307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:07:10.247622Z","caller":"traceutil/trace.go:171","msg":"trace[405325789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25865; }","duration":"228.140248ms","start":"2026-07-08T03:07:10.019463Z","end":"2026-07-08T03:07:10.247603Z","steps":["trace[405325789] 'agreement among raft nodes before linearized reading' (duration: 227.997214ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:10.247627Z","caller":"traceutil/trace.go:171","msg":"trace[1214592207] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25865; }","duration":"178.280269ms","start":"2026-07-08T03:07:10.069328Z","end":"2026-07-08T03:07:10.247608Z","steps":["trace[1214592207] 'agreement among raft nodes before linearized reading' (duration: 178.202737ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:10.247424Z","caller":"traceutil/trace.go:171","msg":"trace[1997428177] transaction","detail":"{read_only:false; response_revision:25865; number_of_response:1; }","duration":"335.823479ms","start":"2026-07-08T03:07:09.911554Z","end":"2026-07-08T03:07:10.247378Z","steps":["trace[1997428177] 'process raft request' (duration: 315.064949ms)","trace[1997428177] 'compare' (duration: 20.271836ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:10.248776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:09.911536Z","time spent":"337.163557ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:10.538429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.044382ms","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-08T03:07:10.538535Z","caller":"traceutil/trace.go:171","msg":"trace[85938521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25865; }","duration":"160.166985ms","start":"2026-07-08T03:07:10.378322Z","end":"2026-07-08T03:07:10.538489Z","steps":["trace[85938521] 'range keys from in-memory index tree' (duration: 159.856947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:10.870565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.36819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:07:10.870673Z","caller":"traceutil/trace.go:171","msg":"trace[1569006853] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25866; }","duration":"116.540205ms","start":"2026-07-08T03:07:10.754107Z","end":"2026-07-08T03:07:10.870648Z","steps":["trace[1569006853] 'count revisions from in-memory index tree' (duration: 116.277138ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:11.189057Z","caller":"traceutil/trace.go:171","msg":"trace[1085566190] transaction","detail":"{read_only:false; response_revision:25867; number_of_response:1; }","duration":"114.653842ms","start":"2026-07-08T03:07:11.074374Z","end":"2026-07-08T03:07:11.189027Z","steps":["trace[1085566190] 'process raft request' (duration: 114.458916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:11.537793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.712092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T03:07:11.538007Z","caller":"traceutil/trace.go:171","msg":"trace[582948270] transaction","detail":"{read_only:false; response_revision:25869; number_of_response:1; }","duration":"274.776416ms","start":"2026-07-08T03:07:11.263208Z","end":"2026-07-08T03:07:11.537984Z","steps":["trace[582948270] 'process raft request' (duration: 274.699404ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:11.538428Z","caller":"traceutil/trace.go:171","msg":"trace[1007955212] linearizableReadLoop","detail":"{readStateIndex:26727; appliedIndex:26726; }","duration":"289.005466ms","start":"2026-07-08T03:07:11.249377Z","end":"2026-07-08T03:07:11.538383Z","steps":["trace[1007955212] 'read index received' (duration: 101.551543ms)","trace[1007955212] 'applied index is now lower than readState.Index' (duration: 187.445853ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:11.538686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.300445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-08T03:07:11.538731Z","caller":"traceutil/trace.go:171","msg":"trace[1888184041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25869; }","duration":"289.407628ms","start":"2026-07-08T03:07:11.249312Z","end":"2026-07-08T03:07:11.538719Z","steps":["trace[1888184041] 'agreement among raft nodes before linearized reading' (duration: 289.211972ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:11.538726Z","caller":"traceutil/trace.go:171","msg":"trace[1789508086] transaction","detail":"{read_only:false; response_revision:25868; number_of_response:1; }","duration":"302.317871ms","start":"2026-07-08T03:07:11.236016Z","end":"2026-07-08T03:07:11.538333Z","steps":["trace[1789508086] 'process raft request' (duration: 114.95985ms)","trace[1789508086] 'compare' (duration: 186.160326ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:11.538933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:11.235992Z","time spent":"302.840086ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:11.766561Z","caller":"traceutil/trace.go:171","msg":"trace[22657800] transaction","detail":"{read_only:false; response_revision:25870; number_of_response:1; }","duration":"323.567174ms","start":"2026-07-08T03:07:11.442968Z","end":"2026-07-08T03:07:11.766535Z","steps":["trace[22657800] 'process raft request' (duration: 323.358248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:11.766739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:11.442943Z","time spent":"323.697618ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:11.859617Z","caller":"traceutil/trace.go:171","msg":"trace[491986645] linearizableReadLoop","detail":"{readStateIndex:26730; appliedIndex:26728; }","duration":"257.358602ms","start":"2026-07-08T03:07:11.602232Z","end":"2026-07-08T03:07:11.85959Z","steps":["trace[491986645] 'read index received' (duration: 164.15415ms)","trace[491986645] 'applied index is now lower than readState.Index' (duration: 93.203122ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:11.859708Z","caller":"traceutil/trace.go:171","msg":"trace[1904822472] transaction","detail":"{read_only:false; response_revision:25871; number_of_response:1; }","duration":"316.343226ms","start":"2026-07-08T03:07:11.543306Z","end":"2026-07-08T03:07:11.859649Z","steps":["trace[1904822472] 'process raft request' (duration: 314.724469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:11.859857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.60116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-08T03:07:11.859877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:11.543286Z","time spent":"316.512061ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:11.8599Z","caller":"traceutil/trace.go:171","msg":"trace[1221614980] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25871; }","duration":"257.661712ms","start":"2026-07-08T03:07:11.602226Z","end":"2026-07-08T03:07:11.859888Z","steps":["trace[1221614980] 'agreement among raft nodes before linearized reading' (duration: 257.502867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:11.860153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.388147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-08T03:07:11.860223Z","caller":"traceutil/trace.go:171","msg":"trace[2005415104] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25871; }","duration":"227.49289ms","start":"2026-07-08T03:07:11.632714Z","end":"2026-07-08T03:07:11.860207Z","steps":["trace[2005415104] 'agreement among raft nodes before linearized reading' (duration: 227.304955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:12.199311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.167837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T03:07:12.199411Z","caller":"traceutil/trace.go:171","msg":"trace[514105786] transaction","detail":"{read_only:false; response_revision:25873; number_of_response:1; }","duration":"317.557491ms","start":"2026-07-08T03:07:11.881831Z","end":"2026-07-08T03:07:12.199388Z","steps":["trace[514105786] 'process raft request' (duration: 95.249571ms)","trace[514105786] 'compare' (duration: 221.971671ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:12.199449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:11.88181Z","time spent":"317.621584ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:12.200026Z","caller":"traceutil/trace.go:171","msg":"trace[2072366249] transaction","detail":"{read_only:false; response_revision:25874; number_of_response:1; }","duration":"317.994163ms","start":"2026-07-08T03:07:11.882008Z","end":"2026-07-08T03:07:12.200002Z","steps":["trace[2072366249] 'process raft request' (duration: 317.823548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:12.200171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:11.881994Z","time spent":"318.103297ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:12.200384Z","caller":"traceutil/trace.go:171","msg":"trace[1300371550] transaction","detail":"{read_only:false; response_revision:25875; number_of_response:1; }","duration":"317.685844ms","start":"2026-07-08T03:07:11.882674Z","end":"2026-07-08T03:07:12.20036Z","steps":["trace[1300371550] 'process raft request' (duration: 317.281293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:12.20051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:11.882659Z","time spent":"317.766457ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:12.200622Z","caller":"traceutil/trace.go:171","msg":"trace[1780160336] transaction","detail":"{read_only:false; response_revision:25876; number_of_response:1; }","duration":"317.905201ms","start":"2026-07-08T03:07:11.882692Z","end":"2026-07-08T03:07:12.200597Z","steps":["trace[1780160336] 'process raft request' (duration: 317.53243ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:12.200751Z","caller":"traceutil/trace.go:171","msg":"trace[561093902] transaction","detail":"{read_only:false; response_revision:25877; number_of_response:1; }","duration":"317.768237ms","start":"2026-07-08T03:07:11.882971Z","end":"2026-07-08T03:07:12.200739Z","steps":["trace[561093902] 'process raft request' (duration: 317.564891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:12.200786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:11.882678Z","time spent":"318.020484ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:12.200816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:11.882959Z","time spent":"317.823478ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:12.200843Z","caller":"traceutil/trace.go:171","msg":"trace[2064548156] linearizableReadLoop","detail":"{readStateIndex:26738; appliedIndex:26731; }","duration":"304.657998ms","start":"2026-07-08T03:07:11.89617Z","end":"2026-07-08T03:07:12.200828Z","steps":["trace[2064548156] 'read index received' (duration: 80.924926ms)","trace[2064548156] 'applied index is now lower than readState.Index' (duration: 223.731792ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:12.200912Z","caller":"traceutil/trace.go:171","msg":"trace[1878159177] transaction","detail":"{read_only:false; response_revision:25878; number_of_response:1; }","duration":"317.406417ms","start":"2026-07-08T03:07:11.883494Z","end":"2026-07-08T03:07:12.200901Z","steps":["trace[1878159177] 'process raft request' (duration: 317.18724ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:12.200937Z","caller":"traceutil/trace.go:171","msg":"trace[384350423] transaction","detail":"{read_only:false; response_revision:25879; number_of_response:1; }","duration":"317.210072ms","start":"2026-07-08T03:07:11.883717Z","end":"2026-07-08T03:07:12.200927Z","steps":["trace[384350423] 'process raft request' (duration: 317.069447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:12.20097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:11.883487Z","time spent":"317.452758ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:12.201031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:11.883699Z","time spent":"317.290143ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:12.201141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.972277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:07:12.20118Z","caller":"traceutil/trace.go:171","msg":"trace[1995168704] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25879; }","duration":"305.041289ms","start":"2026-07-08T03:07:11.896128Z","end":"2026-07-08T03:07:12.201169Z","steps":["trace[1995168704] 'agreement among raft nodes before linearized reading' (duration: 304.976148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:12.201217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:11.89611Z","time spent":"305.096321ms","remote":"127.0.0.1:37782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-07-08T03:07:12.20126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.046695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:07:12.201315Z","caller":"traceutil/trace.go:171","msg":"trace[1427693238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25879; }","duration":"184.146867ms","start":"2026-07-08T03:07:12.017157Z","end":"2026-07-08T03:07:12.201304Z","steps":["trace[1427693238] 'agreement among raft nodes before linearized reading' (duration: 184.057935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:12.201323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.825067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T03:07:12.2014Z","caller":"traceutil/trace.go:171","msg":"trace[1282696733] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:25879; }","duration":"220.948012ms","start":"2026-07-08T03:07:11.980438Z","end":"2026-07-08T03:07:12.201386Z","steps":["trace[1282696733] 'agreement among raft nodes before linearized reading' (duration: 220.801866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:12.20283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.40763ms","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-08T03:07:12.202916Z","caller":"traceutil/trace.go:171","msg":"trace[217447338] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25879; }","duration":"100.516983ms","start":"2026-07-08T03:07:12.102367Z","end":"2026-07-08T03:07:12.202884Z","steps":["trace[217447338] 'agreement among raft nodes before linearized reading' (duration: 98.68358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:12.203112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.498452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-08T03:07:12.203201Z","caller":"traceutil/trace.go:171","msg":"trace[661870506] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25879; }","duration":"272.384196ms","start":"2026-07-08T03:07:11.930801Z","end":"2026-07-08T03:07:12.203185Z","steps":["trace[661870506] 'agreement among raft nodes before linearized reading' (duration: 270.232684ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:12.558629Z","caller":"traceutil/trace.go:171","msg":"trace[1997375697] linearizableReadLoop","detail":"{readStateIndex:26740; appliedIndex:26739; }","duration":"273.810538ms","start":"2026-07-08T03:07:12.284797Z","end":"2026-07-08T03:07:12.558608Z","steps":["trace[1997375697] 'read index received' (duration: 273.740496ms)","trace[1997375697] 'applied index is now lower than readState.Index' (duration: 69.162µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:12.558713Z","caller":"traceutil/trace.go:171","msg":"trace[1642165248] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"301.675743ms","start":"2026-07-08T03:07:12.257004Z","end":"2026-07-08T03:07:12.55868Z","steps":["trace[1642165248] 'process raft request' (duration: 301.470447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:12.558752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.940852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:07:12.55878Z","caller":"traceutil/trace.go:171","msg":"trace[80581763] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25881; }","duration":"274.029394ms","start":"2026-07-08T03:07:12.284743Z","end":"2026-07-08T03:07:12.558772Z","steps":["trace[80581763] 'agreement among raft nodes before linearized reading' (duration: 273.938372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:12.558851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:12.256987Z","time spent":"301.793716ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:12.848399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.268014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T03:07:12.848561Z","caller":"traceutil/trace.go:171","msg":"trace[1049302963] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"278.699009ms","start":"2026-07-08T03:07:12.56984Z","end":"2026-07-08T03:07:12.848539Z","steps":["trace[1049302963] 'process raft request' (duration: 132.225648ms)","trace[1049302963] 'compare' (duration: 146.133921ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:12.924405Z","caller":"traceutil/trace.go:171","msg":"trace[127989966] linearizableReadLoop","detail":"{readStateIndex:26742; appliedIndex:26740; }","duration":"217.561813ms","start":"2026-07-08T03:07:12.706804Z","end":"2026-07-08T03:07:12.924366Z","steps":["trace[127989966] 'read index received' (duration: 136.847452ms)","trace[127989966] 'applied index is now lower than readState.Index' (duration: 80.710721ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:12.92469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.857892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-08T03:07:12.924774Z","caller":"traceutil/trace.go:171","msg":"trace[449103534] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25882; }","duration":"217.960675ms","start":"2026-07-08T03:07:12.706794Z","end":"2026-07-08T03:07:12.924754Z","steps":["trace[449103534] 'agreement among raft nodes before linearized reading' (duration: 217.690768ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:13.330759Z","caller":"traceutil/trace.go:171","msg":"trace[114062637] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"152.859895ms","start":"2026-07-08T03:07:13.177883Z","end":"2026-07-08T03:07:13.330743Z","steps":["trace[114062637] 'process raft request' (duration: 152.737921ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:13.493487Z","caller":"traceutil/trace.go:171","msg":"trace[329363187] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"306.04041ms","start":"2026-07-08T03:07:13.187418Z","end":"2026-07-08T03:07:13.493458Z","steps":["trace[329363187] 'process raft request' (duration: 277.746193ms)","trace[329363187] 'compare' (duration: 28.110402ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:13.493489Z","caller":"traceutil/trace.go:171","msg":"trace[932807329] linearizableReadLoop","detail":"{readStateIndex:26744; appliedIndex:26742; }","duration":"299.918562ms","start":"2026-07-08T03:07:13.193532Z","end":"2026-07-08T03:07:13.493451Z","steps":["trace[932807329] 'read index received' (duration: 137.187252ms)","trace[932807329] 'applied index is now lower than readState.Index' (duration: 162.7295ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:13.493669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.145379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-08T03:07:13.49371Z","caller":"traceutil/trace.go:171","msg":"trace[112980352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25884; }","duration":"300.230831ms","start":"2026-07-08T03:07:13.193469Z","end":"2026-07-08T03:07:13.4937Z","steps":["trace[112980352] 'agreement among raft nodes before linearized reading' (duration: 300.069476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:13.493719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.318591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-08T03:07:13.493674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:13.187398Z","time spent":"306.138582ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:13.493733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:13.193446Z","time spent":"300.281373ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T03:07:13.493922Z","caller":"traceutil/trace.go:171","msg":"trace[1651864383] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:25884; }","duration":"115.421663ms","start":"2026-07-08T03:07:13.378323Z","end":"2026-07-08T03:07:13.493745Z","steps":["trace[1651864383] 'agreement among raft nodes before linearized reading' (duration: 115.31386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:18.035302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221030493529300,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T03:07:18.044225Z","caller":"traceutil/trace.go:171","msg":"trace[391674471] transaction","detail":"{read_only:false; response_revision:25914; number_of_response:1; }","duration":"607.34273ms","start":"2026-07-08T03:07:17.436857Z","end":"2026-07-08T03:07:18.0442Z","steps":["trace[391674471] 'process raft request' (duration: 607.185776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:18.044446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:17.436834Z","time spent":"607.483445ms","remote":"127.0.0.1:42866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:18.343152Z","caller":"traceutil/trace.go:171","msg":"trace[1432412773] linearizableReadLoop","detail":"{readStateIndex:26776; appliedIndex:26774; }","duration":"808.473509ms","start":"2026-07-08T03:07:17.534633Z","end":"2026-07-08T03:07:18.343106Z","steps":["trace[1432412773] 'read index received' (duration: 509.453903ms)","trace[1432412773] 'applied index is now lower than readState.Index' (duration: 299.018246ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:18.343277Z","caller":"traceutil/trace.go:171","msg":"trace[358866371] transaction","detail":"{read_only:false; response_revision:25915; number_of_response:1; }","duration":"785.289219ms","start":"2026-07-08T03:07:17.557974Z","end":"2026-07-08T03:07:18.343263Z","steps":["trace[358866371] 'process raft request' (duration: 698.190213ms)","trace[358866371] 'compare' (duration: 86.56762ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:18.343393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:17.557948Z","time spent":"785.353481ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:18.343601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.960064ms","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-08T03:07:18.343675Z","caller":"traceutil/trace.go:171","msg":"trace[507496717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25915; }","duration":"809.076327ms","start":"2026-07-08T03:07:17.53458Z","end":"2026-07-08T03:07:18.343656Z","steps":["trace[507496717] 'agreement among raft nodes before linearized reading' (duration: 808.846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:18.343714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:17.53456Z","time spent":"809.145678ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T03:07:18.347763Z","caller":"traceutil/trace.go:171","msg":"trace[431433052] transaction","detail":"{read_only:false; response_revision:25916; number_of_response:1; }","duration":"128.484751ms","start":"2026-07-08T03:07:18.219244Z","end":"2026-07-08T03:07:18.347729Z","steps":["trace[431433052] 'process raft request' (duration: 128.220763ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:18.348113Z","caller":"traceutil/trace.go:171","msg":"trace[975494581] transaction","detail":"{read_only:false; response_revision:25917; number_of_response:1; }","duration":"127.745319ms","start":"2026-07-08T03:07:18.220339Z","end":"2026-07-08T03:07:18.348085Z","steps":["trace[975494581] 'process raft request' (duration: 127.345108ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:18.348262Z","caller":"traceutil/trace.go:171","msg":"trace[1984437589] transaction","detail":"{read_only:false; response_revision:25918; number_of_response:1; }","duration":"127.896754ms","start":"2026-07-08T03:07:18.220347Z","end":"2026-07-08T03:07:18.348244Z","steps":["trace[1984437589] 'process raft request' (duration: 127.619026ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:18.348301Z","caller":"traceutil/trace.go:171","msg":"trace[421835792] transaction","detail":"{read_only:false; response_revision:25919; number_of_response:1; }","duration":"126.0208ms","start":"2026-07-08T03:07:18.222264Z","end":"2026-07-08T03:07:18.348284Z","steps":["trace[421835792] 'process raft request' (duration: 125.860785ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:18.348435Z","caller":"traceutil/trace.go:171","msg":"trace[255919682] transaction","detail":"{read_only:false; response_revision:25922; number_of_response:1; }","duration":"122.870509ms","start":"2026-07-08T03:07:18.225551Z","end":"2026-07-08T03:07:18.348421Z","steps":["trace[255919682] 'process raft request' (duration: 122.701064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:18.348475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.686322ms","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-08T03:07:18.348597Z","caller":"traceutil/trace.go:171","msg":"trace[1067588233] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"123.249079ms","start":"2026-07-08T03:07:18.225324Z","end":"2026-07-08T03:07:18.348573Z","steps":["trace[1067588233] 'process raft request' (duration: 122.892689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:18.348621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.383885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:07:18.34865Z","caller":"traceutil/trace.go:171","msg":"trace[987104967] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25922; }","duration":"463.899108ms","start":"2026-07-08T03:07:17.884724Z","end":"2026-07-08T03:07:18.348623Z","steps":["trace[987104967] 'agreement among raft nodes before linearized reading' (duration: 463.574338ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:18.348679Z","caller":"traceutil/trace.go:171","msg":"trace[1653809153] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25922; }","duration":"425.481499ms","start":"2026-07-08T03:07:17.92318Z","end":"2026-07-08T03:07:18.348662Z","steps":["trace[1653809153] 'agreement among raft nodes before linearized reading' (duration: 425.391436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:18.348699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.083082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-08T03:07:18.348704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:17.884706Z","time spent":"463.988351ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T03:07:18.348715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:17.923159Z","time spent":"425.54662ms","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"info","ts":"2026-07-08T03:07:18.348759Z","caller":"traceutil/trace.go:171","msg":"trace[1890248046] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25922; }","duration":"447.148354ms","start":"2026-07-08T03:07:17.901597Z","end":"2026-07-08T03:07:18.348745Z","steps":["trace[1890248046] 'agreement among raft nodes before linearized reading' (duration: 446.98456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:18.348804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:17.901553Z","time spent":"447.239547ms","remote":"127.0.0.1:43084","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-08T03:07:18.348782Z","caller":"traceutil/trace.go:171","msg":"trace[264499272] transaction","detail":"{read_only:false; response_revision:25920; number_of_response:1; }","duration":"123.880438ms","start":"2026-07-08T03:07:18.224882Z","end":"2026-07-08T03:07:18.348762Z","steps":["trace[264499272] 'process raft request' (duration: 123.297521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:18.348877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.90545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-08T03:07:18.349057Z","caller":"traceutil/trace.go:171","msg":"trace[1667768436] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25922; }","duration":"301.066045ms","start":"2026-07-08T03:07:18.047914Z","end":"2026-07-08T03:07:18.34898Z","steps":["trace[1667768436] 'agreement among raft nodes before linearized reading' (duration: 300.752966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:18.349177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:18.04786Z","time spent":"301.289661ms","remote":"127.0.0.1:42994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-08T03:07:23.01319Z","caller":"traceutil/trace.go:171","msg":"trace[1552185569] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"108.218386ms","start":"2026-07-08T03:07:22.90492Z","end":"2026-07-08T03:07:23.013139Z","steps":["trace[1552185569] 'process raft request' (duration: 108.074582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:23.449919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.180178ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-08T03:07:23.449994Z","caller":"traceutil/trace.go:171","msg":"trace[1759013378] linearizableReadLoop","detail":"{readStateIndex:26817; appliedIndex:26816; }","duration":"251.619037ms","start":"2026-07-08T03:07:23.19836Z","end":"2026-07-08T03:07:23.449979Z","steps":["trace[1759013378] 'read index received' (duration: 75.45613ms)","trace[1759013378] 'applied index is now lower than readState.Index' (duration: 176.161837ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:23.450163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.765241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:07:23.450219Z","caller":"traceutil/trace.go:171","msg":"trace[1359786239] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25955; }","duration":"251.891605ms","start":"2026-07-08T03:07:23.198313Z","end":"2026-07-08T03:07:23.450204Z","steps":["trace[1359786239] 'agreement among raft nodes before linearized reading' (duration: 251.769191ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:28.270322Z","caller":"traceutil/trace.go:171","msg":"trace[236925194] linearizableReadLoop","detail":"{readStateIndex:26911; appliedIndex:26910; }","duration":"144.37925ms","start":"2026-07-08T03:07:28.125917Z","end":"2026-07-08T03:07:28.270296Z","steps":["trace[236925194] 'read index received' (duration: 144.174433ms)","trace[236925194] 'applied index is now lower than readState.Index' (duration: 203.837µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:07:28.270479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.547665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2f3e946b-b7d8-421d-a108-5f7b6258199b\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-07-08T03:07:28.27052Z","caller":"traceutil/trace.go:171","msg":"trace[68147442] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2f3e946b-b7d8-421d-a108-5f7b6258199b; range_end:; response_count:1; response_revision:26048; }","duration":"144.651088ms","start":"2026-07-08T03:07:28.125857Z","end":"2026-07-08T03:07:28.270508Z","steps":["trace[68147442] 'agreement among raft nodes before linearized reading' (duration: 144.562336ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:28.270483Z","caller":"traceutil/trace.go:171","msg":"trace[1587732502] transaction","detail":"{read_only:false; response_revision:26048; number_of_response:1; }","duration":"162.751811ms","start":"2026-07-08T03:07:28.107595Z","end":"2026-07-08T03:07:28.270347Z","steps":["trace[1587732502] 'process raft request' (duration: 162.555295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:28.27067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.608486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-08T03:07:28.270733Z","caller":"traceutil/trace.go:171","msg":"trace[773271712] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:26048; }","duration":"118.733549ms","start":"2026-07-08T03:07:28.151986Z","end":"2026-07-08T03:07:28.27072Z","steps":["trace[773271712] 'agreement among raft nodes before linearized reading' (duration: 118.479232ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:28.519753Z","caller":"traceutil/trace.go:171","msg":"trace[1934091421] transaction","detail":"{read_only:false; response_revision:26049; number_of_response:1; }","duration":"133.500106ms","start":"2026-07-08T03:07:28.386216Z","end":"2026-07-08T03:07:28.519716Z","steps":["trace[1934091421] 'process raft request' (duration: 130.153978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:28.519796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.114346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-07-08T03:07:28.519868Z","caller":"traceutil/trace.go:171","msg":"trace[1307754838] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26048; }","duration":"185.232489ms","start":"2026-07-08T03:07:28.334615Z","end":"2026-07-08T03:07:28.519847Z","steps":["trace[1307754838] 'range keys from in-memory index tree' (duration: 184.962172ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:28.76822Z","caller":"traceutil/trace.go:171","msg":"trace[1294024782] transaction","detail":"{read_only:false; response_revision:26051; number_of_response:1; }","duration":"370.132119ms","start":"2026-07-08T03:07:28.398068Z","end":"2026-07-08T03:07:28.7682Z","steps":["trace[1294024782] 'process raft request' (duration: 370.052057ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:28.768302Z","caller":"traceutil/trace.go:171","msg":"trace[171178156] transaction","detail":"{read_only:false; response_revision:26050; number_of_response:1; }","duration":"381.832417ms","start":"2026-07-08T03:07:28.386409Z","end":"2026-07-08T03:07:28.768241Z","steps":["trace[171178156] 'process raft request' (duration: 381.532699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:28.7684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.398058Z","time spent":"370.234593ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:28.768435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.386402Z","time spent":"381.955041ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:28.768592Z","caller":"traceutil/trace.go:171","msg":"trace[1322853419] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"364.841657ms","start":"2026-07-08T03:07:28.403718Z","end":"2026-07-08T03:07:28.768559Z","steps":["trace[1322853419] 'process raft request' (duration: 364.441225ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:28.76863Z","caller":"traceutil/trace.go:171","msg":"trace[2003685449] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"364.410914ms","start":"2026-07-08T03:07:28.404211Z","end":"2026-07-08T03:07:28.768622Z","steps":["trace[2003685449] 'process raft request' (duration: 364.207269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:28.768667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.404203Z","time spent":"364.442455ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:28.768693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.403709Z","time spent":"364.938379ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:28.768702Z","caller":"traceutil/trace.go:171","msg":"trace[1459189141] linearizableReadLoop","detail":"{readStateIndex:26919; appliedIndex:26911; }","duration":"356.65186ms","start":"2026-07-08T03:07:28.412028Z","end":"2026-07-08T03:07:28.76868Z","steps":["trace[1459189141] 'read index received' (duration: 104.293022ms)","trace[1459189141] 'applied index is now lower than readState.Index' (duration: 252.357838ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:28.768716Z","caller":"traceutil/trace.go:171","msg":"trace[1743165108] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"364.353193ms","start":"2026-07-08T03:07:28.40432Z","end":"2026-07-08T03:07:28.768673Z","steps":["trace[1743165108] 'process raft request' (duration: 364.232779ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:28.768764Z","caller":"traceutil/trace.go:171","msg":"trace[1148055698] transaction","detail":"{read_only:false; response_revision:26056; number_of_response:1; }","duration":"360.12196ms","start":"2026-07-08T03:07:28.408628Z","end":"2026-07-08T03:07:28.76875Z","steps":["trace[1148055698] 'process raft request' (duration: 360.006657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:28.768829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.40859Z","time spent":"360.211333ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:28.768888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.404301Z","time spent":"364.493417ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:28.768912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.865477ms","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-08T03:07:28.768951Z","caller":"traceutil/trace.go:171","msg":"trace[545109464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26056; }","duration":"356.939969ms","start":"2026-07-08T03:07:28.411999Z","end":"2026-07-08T03:07:28.768939Z","steps":["trace[545109464] 'agreement among raft nodes before linearized reading' (duration: 356.791345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:28.768991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.41199Z","time spent":"356.993371ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T03:07:28.768959Z","caller":"traceutil/trace.go:171","msg":"trace[1134158679] transaction","detail":"{read_only:false; response_revision:26055; number_of_response:1; }","duration":"360.402889ms","start":"2026-07-08T03:07:28.408416Z","end":"2026-07-08T03:07:28.768818Z","steps":["trace[1134158679] 'process raft request' (duration: 360.179832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:28.769232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.408397Z","time spent":"360.731598ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:28.91819Z","caller":"traceutil/trace.go:171","msg":"trace[538499394] linearizableReadLoop","detail":"{readStateIndex:26920; appliedIndex:26919; }","duration":"149.402345ms","start":"2026-07-08T03:07:28.768769Z","end":"2026-07-08T03:07:28.918171Z","steps":["trace[538499394] 'read index received' (duration: 149.22548ms)","trace[538499394] 'applied index is now lower than readState.Index' (duration: 175.845µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:28.91834Z","caller":"traceutil/trace.go:171","msg":"trace[1113939973] transaction","detail":"{read_only:false; response_revision:26057; number_of_response:1; }","duration":"388.748798ms","start":"2026-07-08T03:07:28.529562Z","end":"2026-07-08T03:07:28.918311Z","steps":["trace[1113939973] 'process raft request' (duration: 388.428149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:28.91837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.814628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T03:07:28.918426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.188539ms","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-08T03:07:28.918437Z","caller":"traceutil/trace.go:171","msg":"trace[1656264775] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26057; }","duration":"487.923631ms","start":"2026-07-08T03:07:28.430498Z","end":"2026-07-08T03:07:28.918422Z","steps":["trace[1656264775] 'agreement among raft nodes before linearized reading' (duration: 487.763616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:28.918422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.03491ms","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-08T03:07:28.918474Z","caller":"traceutil/trace.go:171","msg":"trace[4236314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26057; }","duration":"506.260211ms","start":"2026-07-08T03:07:28.412201Z","end":"2026-07-08T03:07:28.918462Z","steps":["trace[4236314] 'agreement among raft nodes before linearized reading' (duration: 506.110327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:28.918479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.43049Z","time spent":"487.982022ms","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-07-08T03:07:28.918505Z","caller":"traceutil/trace.go:171","msg":"trace[196742377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26057; }","duration":"295.160684ms","start":"2026-07-08T03:07:28.623323Z","end":"2026-07-08T03:07:28.918483Z","steps":["trace[196742377] 'agreement among raft nodes before linearized reading' (duration: 294.888766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:28.918523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.412197Z","time spent":"506.318323ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T03:07:28.918504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.529544Z","time spent":"388.872101ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:07:28.919064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.476963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-08T03:07:28.9191Z","caller":"traceutil/trace.go:171","msg":"trace[2053299499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26057; }","duration":"501.556015ms","start":"2026-07-08T03:07:28.417535Z","end":"2026-07-08T03:07:28.919091Z","steps":["trace[2053299499] 'agreement among raft nodes before linearized reading' (duration: 500.730231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:28.91912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.417516Z","time spent":"501.598627ms","remote":"127.0.0.1:43084","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-08T03:07:29.0788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.732767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T03:07:29.078917Z","caller":"traceutil/trace.go:171","msg":"trace[1825891630] transaction","detail":"{read_only:false; response_revision:26058; number_of_response:1; }","duration":"305.77594ms","start":"2026-07-08T03:07:28.773123Z","end":"2026-07-08T03:07:29.078899Z","steps":["trace[1825891630] 'process raft request' (duration: 193.863988ms)","trace[1825891630] 'compare' (duration: 111.656785ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:29.078977Z","caller":"traceutil/trace.go:171","msg":"trace[804102634] transaction","detail":"{read_only:false; response_revision:26061; number_of_response:1; }","duration":"154.978235ms","start":"2026-07-08T03:07:28.923987Z","end":"2026-07-08T03:07:29.078965Z","steps":["trace[804102634] 'process raft request' (duration: 154.952124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:29.079018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:07:28.773099Z","time spent":"305.865413ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:07:29.079101Z","caller":"traceutil/trace.go:171","msg":"trace[1218893025] transaction","detail":"{read_only:false; response_revision:26059; number_of_response:1; }","duration":"156.86145ms","start":"2026-07-08T03:07:28.922234Z","end":"2026-07-08T03:07:29.079095Z","steps":["trace[1218893025] 'process raft request' (duration: 156.640914ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:29.079142Z","caller":"traceutil/trace.go:171","msg":"trace[1704008278] linearizableReadLoop","detail":"{readStateIndex:26921; appliedIndex:26920; }","duration":"160.895666ms","start":"2026-07-08T03:07:28.918241Z","end":"2026-07-08T03:07:29.079136Z","steps":["trace[1704008278] 'read index received' (duration: 55.609736ms)","trace[1704008278] 'applied index is now lower than readState.Index' (duration: 105.28532ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:29.07917Z","caller":"traceutil/trace.go:171","msg":"trace[2141226046] transaction","detail":"{read_only:false; response_revision:26060; number_of_response:1; }","duration":"156.398087ms","start":"2026-07-08T03:07:28.922765Z","end":"2026-07-08T03:07:29.079163Z","steps":["trace[2141226046] 'process raft request' (duration: 156.1532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:29.079269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.654258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:07:29.079303Z","caller":"traceutil/trace.go:171","msg":"trace[1504493817] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:26061; }","duration":"296.709879ms","start":"2026-07-08T03:07:28.782584Z","end":"2026-07-08T03:07:29.079294Z","steps":["trace[1504493817] 'agreement among raft nodes before linearized reading' (duration: 296.654727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:29.079442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.41393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:07:29.079467Z","caller":"traceutil/trace.go:171","msg":"trace[1323814815] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26061; }","duration":"154.4532ms","start":"2026-07-08T03:07:28.925007Z","end":"2026-07-08T03:07:29.07946Z","steps":["trace[1323814815] 'agreement among raft nodes before linearized reading' (duration: 154.413329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:29.079585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.077222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:07:29.07961Z","caller":"traceutil/trace.go:171","msg":"trace[2139567343] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:26061; }","duration":"125.174434ms","start":"2026-07-08T03:07:28.95443Z","end":"2026-07-08T03:07:29.079604Z","steps":["trace[2139567343] 'agreement among raft nodes before linearized reading' (duration: 125.133193ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:52.939834Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a05.wal"} {"level":"info","ts":"2026-07-08T03:07:59.367155Z","caller":"traceutil/trace.go:171","msg":"trace[617911174] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"150.145307ms","start":"2026-07-08T03:07:59.216983Z","end":"2026-07-08T03:07:59.367129Z","steps":["trace[617911174] 'process raft request' (duration: 149.999153ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:59.367298Z","caller":"traceutil/trace.go:171","msg":"trace[142746515] linearizableReadLoop","detail":"{readStateIndex:27192; appliedIndex:27189; }","duration":"147.267812ms","start":"2026-07-08T03:07:59.220023Z","end":"2026-07-08T03:07:59.367291Z","steps":["trace[142746515] 'read index received' (duration: 83.13066ms)","trace[142746515] 'applied index is now lower than readState.Index' (duration: 64.136342ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:59.36737Z","caller":"traceutil/trace.go:171","msg":"trace[1445579935] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"149.670222ms","start":"2026-07-08T03:07:59.217693Z","end":"2026-07-08T03:07:59.367363Z","steps":["trace[1445579935] 'process raft request' (duration: 149.399695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:59.367516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.481768ms","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-08T03:07:59.367583Z","caller":"traceutil/trace.go:171","msg":"trace[1480181629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26322; }","duration":"147.585631ms","start":"2026-07-08T03:07:59.21999Z","end":"2026-07-08T03:07:59.367576Z","steps":["trace[1480181629] 'agreement among raft nodes before linearized reading' (duration: 147.440767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:07:59.367676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.645013ms","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-08T03:07:59.367694Z","caller":"traceutil/trace.go:171","msg":"trace[1520465585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26322; }","duration":"147.705635ms","start":"2026-07-08T03:07:59.219983Z","end":"2026-07-08T03:07:59.367689Z","steps":["trace[1520465585] 'agreement among raft nodes before linearized reading' (duration: 147.651653ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:07:59.50073Z","caller":"traceutil/trace.go:171","msg":"trace[1853585150] transaction","detail":"{read_only:false; response_revision:26323; number_of_response:1; }","duration":"127.928355ms","start":"2026-07-08T03:07:59.372782Z","end":"2026-07-08T03:07:59.50071Z","steps":["trace[1853585150] 'process raft request' (duration: 55.028609ms)","trace[1853585150] 'compare' (duration: 72.583377ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:07:59.501018Z","caller":"traceutil/trace.go:171","msg":"trace[2120716263] transaction","detail":"{read_only:false; response_revision:26324; number_of_response:1; }","duration":"127.861493ms","start":"2026-07-08T03:07:59.373127Z","end":"2026-07-08T03:07:59.500989Z","steps":["trace[2120716263] 'process raft request' (duration: 127.542274ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:00.991589Z","caller":"traceutil/trace.go:171","msg":"trace[231371842] transaction","detail":"{read_only:false; response_revision:26327; number_of_response:1; }","duration":"118.311286ms","start":"2026-07-08T03:08:00.873256Z","end":"2026-07-08T03:08:00.991567Z","steps":["trace[231371842] 'process raft request' (duration: 118.193613ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:01.186587Z","caller":"traceutil/trace.go:171","msg":"trace[1705177207] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"305.442902ms","start":"2026-07-08T03:08:00.88111Z","end":"2026-07-08T03:08:01.186553Z","steps":["trace[1705177207] 'process raft request' (duration: 305.229295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:01.186899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:08:00.881087Z","time spent":"305.590166ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:08:01.186917Z","caller":"traceutil/trace.go:171","msg":"trace[835267126] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"304.958397ms","start":"2026-07-08T03:08:00.881921Z","end":"2026-07-08T03:08:01.186879Z","steps":["trace[835267126] 'process raft request' (duration: 304.585966ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:01.186954Z","caller":"traceutil/trace.go:171","msg":"trace[1769501018] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"294.740763ms","start":"2026-07-08T03:08:00.892186Z","end":"2026-07-08T03:08:01.186927Z","steps":["trace[1769501018] 'process raft request' (duration: 294.590059ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:01.187004Z","caller":"traceutil/trace.go:171","msg":"trace[441649158] transaction","detail":"{read_only:false; response_revision:26331; number_of_response:1; }","duration":"294.364051ms","start":"2026-07-08T03:08:00.892624Z","end":"2026-07-08T03:08:01.186988Z","steps":["trace[441649158] 'process raft request' (duration: 294.244408ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:01.187008Z","caller":"traceutil/trace.go:171","msg":"trace[288187617] linearizableReadLoop","detail":"{readStateIndex:27203; appliedIndex:27197; }","duration":"168.759424ms","start":"2026-07-08T03:08:01.01823Z","end":"2026-07-08T03:08:01.186989Z","steps":["trace[288187617] 'read index received' (duration: 168.083694ms)","trace[288187617] 'applied index is now lower than readState.Index' (duration: 674.89µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:08:01.187049Z","caller":"traceutil/trace.go:171","msg":"trace[1293839164] transaction","detail":"{read_only:false; response_revision:26333; number_of_response:1; }","duration":"291.533669ms","start":"2026-07-08T03:08:00.895491Z","end":"2026-07-08T03:08:01.187024Z","steps":["trace[1293839164] 'process raft request' (duration: 291.458927ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:01.187077Z","caller":"traceutil/trace.go:171","msg":"trace[286871985] transaction","detail":"{read_only:false; response_revision:26332; number_of_response:1; }","duration":"292.252551ms","start":"2026-07-08T03:08:00.894777Z","end":"2026-07-08T03:08:01.187029Z","steps":["trace[286871985] 'process raft request' (duration: 292.139388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:01.187102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:08:00.881912Z","time spent":"305.090451ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:08:01.187132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.673741ms","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":"warn","ts":"2026-07-08T03:08:01.187132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.883577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:08:01.187191Z","caller":"traceutil/trace.go:171","msg":"trace[1318543423] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26333; }","duration":"106.743853ms","start":"2026-07-08T03:08:01.080413Z","end":"2026-07-08T03:08:01.187157Z","steps":["trace[1318543423] 'agreement among raft nodes before linearized reading' (duration: 106.683971ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:01.187211Z","caller":"traceutil/trace.go:171","msg":"trace[1288985176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26333; }","duration":"169.013262ms","start":"2026-07-08T03:08:01.018185Z","end":"2026-07-08T03:08:01.187199Z","steps":["trace[1288985176] 'agreement among raft nodes before linearized reading' (duration: 168.897599ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:01.648923Z","caller":"traceutil/trace.go:171","msg":"trace[700917046] transaction","detail":"{read_only:false; response_revision:26337; number_of_response:1; }","duration":"137.008237ms","start":"2026-07-08T03:08:01.511894Z","end":"2026-07-08T03:08:01.648902Z","steps":["trace[700917046] 'process raft request' (duration: 136.856332ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:01.722886Z","caller":"traceutil/trace.go:171","msg":"trace[956102703] transaction","detail":"{read_only:false; response_revision:26338; number_of_response:1; }","duration":"210.669574ms","start":"2026-07-08T03:08:01.512189Z","end":"2026-07-08T03:08:01.722858Z","steps":["trace[956102703] 'process raft request' (duration: 210.51783ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:03.323627Z","caller":"traceutil/trace.go:171","msg":"trace[1268960165] transaction","detail":"{read_only:false; response_revision:26342; number_of_response:1; }","duration":"131.324682ms","start":"2026-07-08T03:08:03.192282Z","end":"2026-07-08T03:08:03.323606Z","steps":["trace[1268960165] 'process raft request' (duration: 131.175967ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:03.521269Z","caller":"traceutil/trace.go:171","msg":"trace[2071489078] transaction","detail":"{read_only:false; response_revision:26343; number_of_response:1; }","duration":"328.308552ms","start":"2026-07-08T03:08:03.192933Z","end":"2026-07-08T03:08:03.521242Z","steps":["trace[2071489078] 'process raft request' (duration: 228.384575ms)","trace[2071489078] 'compare' (duration: 99.514545ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:08:03.521429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:08:03.192914Z","time spent":"328.438455ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:08:03.521856Z","caller":"traceutil/trace.go:171","msg":"trace[653708953] transaction","detail":"{read_only:false; response_revision:26347; number_of_response:1; }","duration":"328.210998ms","start":"2026-07-08T03:08:03.193605Z","end":"2026-07-08T03:08:03.521816Z","steps":["trace[653708953] 'process raft request' (duration: 327.92807ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:03.521903Z","caller":"traceutil/trace.go:171","msg":"trace[640804328] transaction","detail":"{read_only:false; response_revision:26345; number_of_response:1; }","duration":"328.382664ms","start":"2026-07-08T03:08:03.193493Z","end":"2026-07-08T03:08:03.521876Z","steps":["trace[640804328] 'process raft request' (duration: 327.88701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:03.522041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:08:03.193483Z","time spent":"328.482306ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:08:03.522054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:08:03.193589Z","time spent":"328.359813ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:08:03.522104Z","caller":"traceutil/trace.go:171","msg":"trace[523042698] transaction","detail":"{read_only:false; response_revision:26346; number_of_response:1; }","duration":"328.589189ms","start":"2026-07-08T03:08:03.1935Z","end":"2026-07-08T03:08:03.522089Z","steps":["trace[523042698] 'process raft request' (duration: 327.953141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:03.522184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:08:03.193482Z","time spent":"328.662961ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:08:03.521728Z","caller":"traceutil/trace.go:171","msg":"trace[37327890] transaction","detail":"{read_only:false; response_revision:26344; number_of_response:1; }","duration":"328.746854ms","start":"2026-07-08T03:08:03.192964Z","end":"2026-07-08T03:08:03.521711Z","steps":["trace[37327890] 'process raft request' (duration: 328.030644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:03.522438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:08:03.192939Z","time spent":"329.444493ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:08:05.378526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.729814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T03:08:05.378628Z","caller":"traceutil/trace.go:171","msg":"trace[1092456053] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:26356; }","duration":"131.894509ms","start":"2026-07-08T03:08:05.246709Z","end":"2026-07-08T03:08:05.378604Z","steps":["trace[1092456053] 'count revisions from in-memory index tree' (duration: 131.628372ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:05.650955Z","caller":"traceutil/trace.go:171","msg":"trace[387497286] transaction","detail":"{read_only:false; response_revision:26360; number_of_response:1; }","duration":"120.268283ms","start":"2026-07-08T03:08:05.530661Z","end":"2026-07-08T03:08:05.650929Z","steps":["trace[387497286] 'process raft request' (duration: 120.088808ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:05.651259Z","caller":"traceutil/trace.go:171","msg":"trace[1942826865] transaction","detail":"{read_only:false; response_revision:26361; number_of_response:1; }","duration":"120.531871ms","start":"2026-07-08T03:08:05.53069Z","end":"2026-07-08T03:08:05.651222Z","steps":["trace[1942826865] 'process raft request' (duration: 120.198121ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:05.651467Z","caller":"traceutil/trace.go:171","msg":"trace[337862263] transaction","detail":"{read_only:false; response_revision:26362; number_of_response:1; }","duration":"120.628833ms","start":"2026-07-08T03:08:05.530822Z","end":"2026-07-08T03:08:05.651451Z","steps":["trace[337862263] 'process raft request' (duration: 120.299434ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:06.031312Z","caller":"traceutil/trace.go:171","msg":"trace[337782669] linearizableReadLoop","detail":"{readStateIndex:27237; appliedIndex:27236; }","duration":"202.92524ms","start":"2026-07-08T03:08:05.828365Z","end":"2026-07-08T03:08:06.03129Z","steps":["trace[337782669] 'read index received' (duration: 126.986646ms)","trace[337782669] 'applied index is now lower than readState.Index' (duration: 75.937353ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:08:06.031487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.115967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-08T03:08:06.031517Z","caller":"traceutil/trace.go:171","msg":"trace[1632025789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26366; }","duration":"203.187029ms","start":"2026-07-08T03:08:05.828322Z","end":"2026-07-08T03:08:06.031509Z","steps":["trace[1632025789] 'agreement among raft nodes before linearized reading' (duration: 203.076536ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:06.031588Z","caller":"traceutil/trace.go:171","msg":"trace[1875810939] transaction","detail":"{read_only:false; response_revision:26366; number_of_response:1; }","duration":"225.545844ms","start":"2026-07-08T03:08:05.805795Z","end":"2026-07-08T03:08:06.03134Z","steps":["trace[1875810939] 'process raft request' (duration: 149.645632ms)","trace[1875810939] 'compare' (duration: 75.754018ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:08:11.782289Z","caller":"traceutil/trace.go:171","msg":"trace[2066177800] transaction","detail":"{read_only:false; response_revision:26406; number_of_response:1; }","duration":"191.49613ms","start":"2026-07-08T03:08:11.59076Z","end":"2026-07-08T03:08:11.782256Z","steps":["trace[2066177800] 'process raft request' (duration: 191.268914ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:11.910294Z","caller":"traceutil/trace.go:171","msg":"trace[532954223] linearizableReadLoop","detail":"{readStateIndex:27283; appliedIndex:27277; }","duration":"163.395268ms","start":"2026-07-08T03:08:11.746863Z","end":"2026-07-08T03:08:11.910258Z","steps":["trace[532954223] 'read index received' (duration: 35.134365ms)","trace[532954223] 'applied index is now lower than readState.Index' (duration: 128.259933ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:08:11.91056Z","caller":"traceutil/trace.go:171","msg":"trace[1470509592] transaction","detail":"{read_only:false; response_revision:26407; number_of_response:1; }","duration":"310.076043ms","start":"2026-07-08T03:08:11.600473Z","end":"2026-07-08T03:08:11.910549Z","steps":["trace[1470509592] 'process raft request' (duration: 309.514156ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:11.910666Z","caller":"traceutil/trace.go:171","msg":"trace[1832493990] transaction","detail":"{read_only:false; response_revision:26411; number_of_response:1; }","duration":"214.430402ms","start":"2026-07-08T03:08:11.696214Z","end":"2026-07-08T03:08:11.910644Z","steps":["trace[1832493990] 'process raft request' (duration: 214.01111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:11.910676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:08:11.600453Z","time spent":"310.141916ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:08:11.910808Z","caller":"traceutil/trace.go:171","msg":"trace[52657084] transaction","detail":"{read_only:false; response_revision:26408; number_of_response:1; }","duration":"244.299293ms","start":"2026-07-08T03:08:11.666495Z","end":"2026-07-08T03:08:11.910794Z","steps":["trace[52657084] 'process raft request' (duration: 243.627103ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:11.910924Z","caller":"traceutil/trace.go:171","msg":"trace[1285131791] transaction","detail":"{read_only:false; response_revision:26409; number_of_response:1; }","duration":"240.253888ms","start":"2026-07-08T03:08:11.670659Z","end":"2026-07-08T03:08:11.910913Z","steps":["trace[1285131791] 'process raft request' (duration: 239.504906ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:11.910996Z","caller":"traceutil/trace.go:171","msg":"trace[1088301011] transaction","detail":"{read_only:false; response_revision:26410; number_of_response:1; }","duration":"238.750574ms","start":"2026-07-08T03:08:11.672238Z","end":"2026-07-08T03:08:11.910988Z","steps":["trace[1088301011] 'process raft request' (duration: 237.958251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:11.911055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.159761ms","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-08T03:08:11.911137Z","caller":"traceutil/trace.go:171","msg":"trace[1417129854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26411; }","duration":"164.295674ms","start":"2026-07-08T03:08:11.746823Z","end":"2026-07-08T03:08:11.911119Z","steps":["trace[1417129854] 'agreement among raft nodes before linearized reading' (duration: 164.027536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:11.91125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.179291ms","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-08T03:08:11.911295Z","caller":"traceutil/trace.go:171","msg":"trace[385109904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26411; }","duration":"137.234482ms","start":"2026-07-08T03:08:11.77405Z","end":"2026-07-08T03:08:11.911284Z","steps":["trace[385109904] 'agreement among raft nodes before linearized reading' (duration: 137.101609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:11.911479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.478721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-08T03:08:11.911516Z","caller":"traceutil/trace.go:171","msg":"trace[2028923201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26411; }","duration":"136.542813ms","start":"2026-07-08T03:08:11.774962Z","end":"2026-07-08T03:08:11.911505Z","steps":["trace[2028923201] 'agreement among raft nodes before linearized reading' (duration: 136.45752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:12.165177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.672525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T03:08:12.165383Z","caller":"traceutil/trace.go:171","msg":"trace[1021752440] transaction","detail":"{read_only:false; response_revision:26412; number_of_response:1; }","duration":"250.797262ms","start":"2026-07-08T03:08:11.914564Z","end":"2026-07-08T03:08:12.165361Z","steps":["trace[1021752440] 'process raft request' (duration: 103.862399ms)","trace[1021752440] 'compare' (duration: 146.50862ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:08:12.165681Z","caller":"traceutil/trace.go:171","msg":"trace[1900302229] transaction","detail":"{read_only:false; response_revision:26413; number_of_response:1; }","duration":"249.69715ms","start":"2026-07-08T03:08:11.915977Z","end":"2026-07-08T03:08:12.165674Z","steps":["trace[1900302229] 'process raft request' (duration: 249.308989ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:12.165738Z","caller":"traceutil/trace.go:171","msg":"trace[1969628999] linearizableReadLoop","detail":"{readStateIndex:27286; appliedIndex:27283; }","duration":"243.053618ms","start":"2026-07-08T03:08:11.922665Z","end":"2026-07-08T03:08:12.165718Z","steps":["trace[1969628999] 'read index received' (duration: 95.781346ms)","trace[1969628999] 'applied index is now lower than readState.Index' (duration: 147.270762ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:08:12.165817Z","caller":"traceutil/trace.go:171","msg":"trace[918164730] transaction","detail":"{read_only:false; response_revision:26414; number_of_response:1; }","duration":"244.977114ms","start":"2026-07-08T03:08:11.920801Z","end":"2026-07-08T03:08:12.165778Z","steps":["trace[918164730] 'process raft request' (duration: 244.540592ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:12.165832Z","caller":"traceutil/trace.go:171","msg":"trace[380980999] transaction","detail":"{read_only:false; response_revision:26415; number_of_response:1; }","duration":"233.07453ms","start":"2026-07-08T03:08:11.932716Z","end":"2026-07-08T03:08:12.16579Z","steps":["trace[380980999] 'process raft request' (duration: 232.659718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:12.166031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.354127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-htj8b\" ","response":"range_response_count:1 size:3719"} {"level":"info","ts":"2026-07-08T03:08:12.166124Z","caller":"traceutil/trace.go:171","msg":"trace[744814066] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-htj8b; range_end:; response_count:1; response_revision:26415; }","duration":"243.4924ms","start":"2026-07-08T03:08:11.92261Z","end":"2026-07-08T03:08:12.166102Z","steps":["trace[744814066] 'agreement among raft nodes before linearized reading' (duration: 243.253024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:12.166232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.436693ms","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-08T03:08:12.166303Z","caller":"traceutil/trace.go:171","msg":"trace[1823631002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26415; }","duration":"115.510865ms","start":"2026-07-08T03:08:12.050776Z","end":"2026-07-08T03:08:12.166287Z","steps":["trace[1823631002] 'agreement among raft nodes before linearized reading' (duration: 115.216416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:12.166021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.497089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:08:12.166358Z","caller":"traceutil/trace.go:171","msg":"trace[1937417090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26415; }","duration":"147.873799ms","start":"2026-07-08T03:08:12.018472Z","end":"2026-07-08T03:08:12.166346Z","steps":["trace[1937417090] 'agreement among raft nodes before linearized reading' (duration: 147.469638ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:32.300963Z","caller":"traceutil/trace.go:171","msg":"trace[1379557068] transaction","detail":"{read_only:false; response_revision:26564; number_of_response:1; }","duration":"106.465635ms","start":"2026-07-08T03:08:32.194447Z","end":"2026-07-08T03:08:32.300912Z","steps":["trace[1379557068] 'process raft request' (duration: 106.267149ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:32.414483Z","caller":"traceutil/trace.go:171","msg":"trace[1563688692] transaction","detail":"{read_only:false; response_revision:26565; number_of_response:1; }","duration":"108.608927ms","start":"2026-07-08T03:08:32.305847Z","end":"2026-07-08T03:08:32.414456Z","steps":["trace[1563688692] 'process raft request' (duration: 106.720492ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:32.414743Z","caller":"traceutil/trace.go:171","msg":"trace[910127431] transaction","detail":"{read_only:false; response_revision:26566; number_of_response:1; }","duration":"108.484253ms","start":"2026-07-08T03:08:32.306223Z","end":"2026-07-08T03:08:32.414707Z","steps":["trace[910127431] 'process raft request' (duration: 108.179944ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:32.4149Z","caller":"traceutil/trace.go:171","msg":"trace[991826692] linearizableReadLoop","detail":"{readStateIndex:27443; appliedIndex:27440; }","duration":"107.409791ms","start":"2026-07-08T03:08:32.307472Z","end":"2026-07-08T03:08:32.414881Z","steps":["trace[991826692] 'read index received' (duration: 105.042362ms)","trace[991826692] 'applied index is now lower than readState.Index' (duration: 2.366009ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:08:32.415045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.567146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-08T03:08:32.415074Z","caller":"traceutil/trace.go:171","msg":"trace[1366974864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26567; }","duration":"107.625627ms","start":"2026-07-08T03:08:32.307439Z","end":"2026-07-08T03:08:32.415065Z","steps":["trace[1366974864] 'agreement among raft nodes before linearized reading' (duration: 107.512404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:08:32.415132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.419511ms","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-08T03:08:32.414971Z","caller":"traceutil/trace.go:171","msg":"trace[615220207] transaction","detail":"{read_only:false; response_revision:26567; number_of_response:1; }","duration":"108.220915ms","start":"2026-07-08T03:08:32.306709Z","end":"2026-07-08T03:08:32.41493Z","steps":["trace[615220207] 'process raft request' (duration: 107.928537ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:32.415173Z","caller":"traceutil/trace.go:171","msg":"trace[1371835945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26567; }","duration":"107.472743ms","start":"2026-07-08T03:08:32.307688Z","end":"2026-07-08T03:08:32.415161Z","steps":["trace[1371835945] 'agreement among raft nodes before linearized reading' (duration: 107.297738ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:32.617239Z","caller":"traceutil/trace.go:171","msg":"trace[1176375318] transaction","detail":"{read_only:false; response_revision:26568; number_of_response:1; }","duration":"197.967336ms","start":"2026-07-08T03:08:32.419244Z","end":"2026-07-08T03:08:32.617211Z","steps":["trace[1176375318] 'process raft request' (duration: 196.957937ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:08:32.61767Z","caller":"traceutil/trace.go:171","msg":"trace[539800185] transaction","detail":"{read_only:false; response_revision:26569; number_of_response:1; }","duration":"198.050448ms","start":"2026-07-08T03:08:32.419603Z","end":"2026-07-08T03:08:32.617653Z","steps":["trace[539800185] 'process raft request' (duration: 197.971246ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:11:04.042675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25224} {"level":"info","ts":"2026-07-08T03:11:04.187796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25224,"took":"142.14502ms","hash":708210440,"current-db-size-bytes":59052032,"current-db-size":"59 MB","current-db-size-in-use-bytes":38092800,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-08T03:11:04.187862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":708210440,"revision":25224,"compact-revision":21973} {"level":"info","ts":"2026-07-08T03:12:40.502456Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T03:12:40.506564Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-08T03:12:40.507021Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-08T03:16:04.050821Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28098} {"level":"info","ts":"2026-07-08T03:16:04.198744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28098,"took":"143.543728ms","hash":1195015342,"current-db-size-bytes":59052032,"current-db-size":"59 MB","current-db-size-in-use-bytes":32952320,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-08T03:16:04.198814Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1195015342,"revision":28098,"compact-revision":25224} {"level":"info","ts":"2026-07-08T03:21:04.056972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30593} {"level":"info","ts":"2026-07-08T03:21:04.206117Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30593,"took":"133.065892ms","hash":1034740225,"current-db-size-bytes":59052032,"current-db-size":"59 MB","current-db-size-in-use-bytes":30568448,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T03:21:04.2062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1034740225,"revision":30593,"compact-revision":28098} {"level":"info","ts":"2026-07-08T03:26:04.062942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32845} {"level":"info","ts":"2026-07-08T03:26:04.210427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32845,"took":"142.080918ms","hash":2735601839,"current-db-size-bytes":59052032,"current-db-size":"59 MB","current-db-size-in-use-bytes":30445568,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-08T03:26:04.210503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2735601839,"revision":32845,"compact-revision":30593} {"level":"info","ts":"2026-07-08T03:31:04.071083Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35115} {"level":"info","ts":"2026-07-08T03:31:04.214796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35115,"took":"140.694516ms","hash":2760162165,"current-db-size-bytes":59052032,"current-db-size":"59 MB","current-db-size-in-use-bytes":31989760,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-08T03:31:04.214877Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2760162165,"revision":35115,"compact-revision":32845} {"level":"info","ts":"2026-07-08T03:34:09.466108Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T03:34:09.471568Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-08T03:34:09.471652Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-08T03:36:04.079586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37377} {"level":"info","ts":"2026-07-08T03:36:04.19762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37377,"took":"111.356339ms","hash":3542153805,"current-db-size-bytes":59473920,"current-db-size":"60 MB","current-db-size-in-use-bytes":32264192,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-08T03:36:04.197676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3542153805,"revision":37377,"compact-revision":35115}