{"level":"warn","ts":"2026-05-27T09:51:18.512912Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T09:51:18.512998Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30: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-05-27T09:51:18.513061Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T09:51:18.513072Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-27T09:51:18.513092Z","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-05-27T09:51:18.513653Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-05-27T09:51:18.51373Z","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.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30: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-05-27T09:51:18.517694Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.756437ms"} {"level":"info","ts":"2026-05-27T09:51:18.523201Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-05-27T09:51:18.523262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T09:51:18.52328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-05-27T09:51:18.523299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T09:51:18.523306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-05-27T09:51:18.523336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-05-27T09:51:18.532079Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T09:51:18.533906Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T09:51:18.535341Z","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-05-27T09:51:18.538163Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T09:51:18.53858Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T09:51:18.538864Z","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-05-27T09:51:18.538953Z","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-05-27T09:51:18.538964Z","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-05-27T09:51:18.540708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-05-27T09:51:18.543155Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-05-27T09:51:18.546229Z","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-05-27T09:51:18.54635Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-27T09:51:18.546465Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-05-27T09:51:18.546636Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T09:51:18.546687Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T09:51:18.724347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T09:51:18.724398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T09:51:18.724429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-05-27T09:51:18.724446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-05-27T09:51:18.724453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-27T09:51:18.724464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-05-27T09:51:18.724478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-05-27T09:51:18.725382Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:51:18.726321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T09:51:18.726324Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T09:51:18.726364Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T09:51:18.726708Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T09:51:18.726761Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T09:51:18.729115Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T09:51:18.729276Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-05-27T09:51:18.72965Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:51:18.729728Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:51:18.729754Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-27T09:52:03.605104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.618667ms","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-05-27T09:52:03.605244Z","caller":"traceutil/trace.go:171","msg":"trace[96105879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"145.812412ms","start":"2026-05-27T09:52:03.459412Z","end":"2026-05-27T09:52:03.605224Z","steps":["trace[96105879] 'range keys from in-memory index tree' (duration: 145.34634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:52:03.891537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.870203ms","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-05-27T09:52:03.891612Z","caller":"traceutil/trace.go:171","msg":"trace[793777860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"165.978957ms","start":"2026-05-27T09:52:03.725616Z","end":"2026-05-27T09:52:03.891595Z","steps":["trace[793777860] 'range keys from in-memory index tree' (duration: 165.7485ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:52:03.893098Z","caller":"traceutil/trace.go:171","msg":"trace[696909430] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"136.141883ms","start":"2026-05-27T09:52:03.756946Z","end":"2026-05-27T09:52:03.893088Z","steps":["trace[696909430] 'process raft request' (duration: 135.752502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:52:04.168901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.767824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-27T09:52:04.169015Z","caller":"traceutil/trace.go:171","msg":"trace[53828332] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:603; }","duration":"173.933508ms","start":"2026-05-27T09:52:03.995045Z","end":"2026-05-27T09:52:04.168979Z","steps":["trace[53828332] 'range keys from in-memory index tree' (duration: 173.63342ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:52:04.476632Z","caller":"traceutil/trace.go:171","msg":"trace[1023063771] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"292.968273ms","start":"2026-05-27T09:52:04.183636Z","end":"2026-05-27T09:52:04.476604Z","steps":["trace[1023063771] 'process raft request' (duration: 292.816009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:52:20.053187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.817447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:52:20.053273Z","caller":"traceutil/trace.go:171","msg":"trace[1108113822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"173.010423ms","start":"2026-05-27T09:52:19.880245Z","end":"2026-05-27T09:52:20.053255Z","steps":["trace[1108113822] 'range keys from in-memory index tree' (duration: 172.712674ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:52:20.123177Z","caller":"traceutil/trace.go:171","msg":"trace[1722983234] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"123.807378ms","start":"2026-05-27T09:52:19.999349Z","end":"2026-05-27T09:52:20.123156Z","steps":["trace[1722983234] 'read index received' (duration: 123.594322ms)","trace[1722983234] 'applied index is now lower than readState.Index' (duration: 210.596µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:52:20.123328Z","caller":"traceutil/trace.go:171","msg":"trace[547422086] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"133.010255ms","start":"2026-05-27T09:52:19.990276Z","end":"2026-05-27T09:52:20.123286Z","steps":["trace[547422086] 'process raft request' (duration: 132.726487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:52:20.123561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.191539ms","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-05-27T09:52:20.12361Z","caller":"traceutil/trace.go:171","msg":"trace[743173078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"124.277561ms","start":"2026-05-27T09:52:19.999321Z","end":"2026-05-27T09:52:20.123599Z","steps":["trace[743173078] 'agreement among raft nodes before linearized reading' (duration: 123.950162ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:53:34.770267Z","caller":"traceutil/trace.go:171","msg":"trace[362972065] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"149.250571ms","start":"2026-05-27T09:53:34.620989Z","end":"2026-05-27T09:53:34.77024Z","steps":["trace[362972065] 'process raft request' (duration: 149.085757ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:53:34.834965Z","caller":"traceutil/trace.go:171","msg":"trace[515097065] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"213.705528ms","start":"2026-05-27T09:53:34.621218Z","end":"2026-05-27T09:53:34.834924Z","steps":["trace[515097065] 'process raft request' (duration: 172.096782ms)","trace[515097065] 'compare' (duration: 41.469782ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:53:35.228086Z","caller":"traceutil/trace.go:171","msg":"trace[743577455] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"168.632595ms","start":"2026-05-27T09:53:35.059421Z","end":"2026-05-27T09:53:35.228053Z","steps":["trace[743577455] 'process raft request' (duration: 168.043638ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:26.579095Z","caller":"traceutil/trace.go:171","msg":"trace[793904767] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2245; }","duration":"117.500592ms","start":"2026-05-27T09:54:26.461565Z","end":"2026-05-27T09:54:26.579066Z","steps":["trace[793904767] 'read index received' (duration: 117.333498ms)","trace[793904767] 'applied index is now lower than readState.Index' (duration: 165.974µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:54:26.579336Z","caller":"traceutil/trace.go:171","msg":"trace[196714315] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"157.604266ms","start":"2026-05-27T09:54:26.421715Z","end":"2026-05-27T09:54:26.579319Z","steps":["trace[196714315] 'process raft request' (duration: 157.181395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:26.57964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.042807ms","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-05-27T09:54:26.579736Z","caller":"traceutil/trace.go:171","msg":"trace[878065659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2163; }","duration":"118.199702ms","start":"2026-05-27T09:54:26.461514Z","end":"2026-05-27T09:54:26.579714Z","steps":["trace[878065659] 'agreement among raft nodes before linearized reading' (duration: 117.998877ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:26.818536Z","caller":"traceutil/trace.go:171","msg":"trace[940237118] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"235.222812ms","start":"2026-05-27T09:54:26.583276Z","end":"2026-05-27T09:54:26.818499Z","steps":["trace[940237118] 'process raft request' (duration: 235.022027ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:26.820519Z","caller":"traceutil/trace.go:171","msg":"trace[1520863020] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"236.053485ms","start":"2026-05-27T09:54:26.584452Z","end":"2026-05-27T09:54:26.820506Z","steps":["trace[1520863020] 'process raft request' (duration: 235.477139ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:58:26.654051Z","caller":"traceutil/trace.go:171","msg":"trace[1813657291] transaction","detail":"{read_only:false; response_revision:3838; number_of_response:1; }","duration":"223.618868ms","start":"2026-05-27T09:58:26.43039Z","end":"2026-05-27T09:58:26.654008Z","steps":["trace[1813657291] 'process raft request' (duration: 130.565141ms)","trace[1813657291] 'compare' (duration: 92.921004ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:58:26.681067Z","caller":"traceutil/trace.go:171","msg":"trace[431336561] linearizableReadLoop","detail":"{readStateIndex:3986; appliedIndex:3985; }","duration":"114.860934ms","start":"2026-05-27T09:58:26.566158Z","end":"2026-05-27T09:58:26.681019Z","steps":["trace[431336561] 'read index received' (duration: 114.647148ms)","trace[431336561] 'applied index is now lower than readState.Index' (duration: 212.846µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:58:26.681153Z","caller":"traceutil/trace.go:171","msg":"trace[1021693888] transaction","detail":"{read_only:false; response_revision:3839; number_of_response:1; }","duration":"192.080941ms","start":"2026-05-27T09:58:26.489045Z","end":"2026-05-27T09:58:26.681126Z","steps":["trace[1021693888] 'process raft request' (duration: 191.818264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:58:26.681311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.132952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-grafana-client-secret\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-05-27T09:58:26.681382Z","caller":"traceutil/trace.go:171","msg":"trace[2007669881] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-grafana-client-secret; range_end:; response_count:1; response_revision:3839; }","duration":"115.258375ms","start":"2026-05-27T09:58:26.566106Z","end":"2026-05-27T09:58:26.681365Z","steps":["trace[2007669881] 'agreement among raft nodes before linearized reading' (duration: 115.038139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:57.985916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.288391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:59:57.986035Z","caller":"traceutil/trace.go:171","msg":"trace[342351081] range","detail":"{range_begin:/registry/clusterroles/vector; range_end:; response_count:0; response_revision:5044; }","duration":"136.456906ms","start":"2026-05-27T09:59:57.849553Z","end":"2026-05-27T09:59:57.98601Z","steps":["trace[342351081] 'range keys from in-memory index tree' (duration: 136.164128ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:59:57.987105Z","caller":"traceutil/trace.go:171","msg":"trace[1865618402] linearizableReadLoop","detail":"{readStateIndex:5236; appliedIndex:5235; }","duration":"108.743375ms","start":"2026-05-27T09:59:57.878337Z","end":"2026-05-27T09:59:57.98708Z","steps":["trace[1865618402] 'read index received' (duration: 108.56928ms)","trace[1865618402] 'applied index is now lower than readState.Index' (duration: 172.715µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:59:57.987166Z","caller":"traceutil/trace.go:171","msg":"trace[911919693] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"115.570395ms","start":"2026-05-27T09:59:57.871569Z","end":"2026-05-27T09:59:57.987139Z","steps":["trace[911919693] 'process raft request' (duration: 115.350339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:59:57.987369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.012592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:59:57.987423Z","caller":"traceutil/trace.go:171","msg":"trace[417877508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5045; }","duration":"109.130336ms","start":"2026-05-27T09:59:57.878283Z","end":"2026-05-27T09:59:57.987413Z","steps":["trace[417877508] 'agreement among raft nodes before linearized reading' (duration: 108.93796ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:59:58.159189Z","caller":"traceutil/trace.go:171","msg":"trace[1419397504] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5045; }","duration":"155.512505ms","start":"2026-05-27T09:59:58.003638Z","end":"2026-05-27T09:59:58.159151Z","steps":["trace[1419397504] 'process raft request' (duration: 118.901707ms)","trace[1419397504] 'compare' (duration: 36.506326ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:59:58.533837Z","caller":"traceutil/trace.go:171","msg":"trace[1210289478] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"162.929771ms","start":"2026-05-27T09:59:58.370871Z","end":"2026-05-27T09:59:58.533801Z","steps":["trace[1210289478] 'process raft request' (duration: 154.529268ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:00:12.045206Z","caller":"traceutil/trace.go:171","msg":"trace[1123947462] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"124.41938ms","start":"2026-05-27T10:00:11.920751Z","end":"2026-05-27T10:00:12.045171Z","steps":["trace[1123947462] 'process raft request' (duration: 124.317397ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:00:12.045487Z","caller":"traceutil/trace.go:171","msg":"trace[763303879] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"133.011249ms","start":"2026-05-27T10:00:11.912444Z","end":"2026-05-27T10:00:12.045455Z","steps":["trace[763303879] 'process raft request' (duration: 62.968641ms)","trace[763303879] 'compare' (duration: 69.419591ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:00:20.866535Z","caller":"traceutil/trace.go:171","msg":"trace[1831643986] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"191.139786ms","start":"2026-05-27T10:00:20.675361Z","end":"2026-05-27T10:00:20.866501Z","steps":["trace[1831643986] 'process raft request' (duration: 190.453687ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:00:35.525788Z","caller":"traceutil/trace.go:171","msg":"trace[2136261334] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"135.767996ms","start":"2026-05-27T10:00:35.389986Z","end":"2026-05-27T10:00:35.525754Z","steps":["trace[2136261334] 'process raft request' (duration: 117.971651ms)","trace[2136261334] 'compare' (duration: 17.579649ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:01:18.765779Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2969} {"level":"info","ts":"2026-05-27T10:01:18.837266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2969,"took":"69.440191ms","hash":1410451403,"current-db-size-bytes":57171968,"current-db-size":"57 MB","current-db-size-in-use-bytes":33599488,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-27T10:01:18.837333Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1410451403,"revision":2969,"compact-revision":-1} {"level":"info","ts":"2026-05-27T10:04:27.250763Z","caller":"traceutil/trace.go:171","msg":"trace[1963757096] transaction","detail":"{read_only:false; response_revision:7276; number_of_response:1; }","duration":"107.318986ms","start":"2026-05-27T10:04:27.14338Z","end":"2026-05-27T10:04:27.250699Z","steps":["trace[1963757096] 'process raft request' (duration: 106.935335ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:04:34.480676Z","caller":"traceutil/trace.go:171","msg":"trace[1817639833] transaction","detail":"{read_only:false; response_revision:7314; number_of_response:1; }","duration":"105.70633ms","start":"2026-05-27T10:04:34.374909Z","end":"2026-05-27T10:04:34.480615Z","steps":["trace[1817639833] 'process raft request' (duration: 81.083798ms)","trace[1817639833] 'compare' (duration: 24.258812ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:06:18.772015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6003} {"level":"info","ts":"2026-05-27T10:06:18.867996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6003,"took":"94.417075ms","hash":3872567331,"current-db-size-bytes":57171968,"current-db-size":"57 MB","current-db-size-in-use-bytes":21659648,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-27T10:06:18.868077Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3872567331,"revision":6003,"compact-revision":2969} {"level":"info","ts":"2026-05-27T10:06:48.056148Z","caller":"traceutil/trace.go:171","msg":"trace[1891912439] linearizableReadLoop","detail":"{readStateIndex:8714; appliedIndex:8713; }","duration":"176.927921ms","start":"2026-05-27T10:06:47.879201Z","end":"2026-05-27T10:06:48.056129Z","steps":["trace[1891912439] 'read index received' (duration: 176.812148ms)","trace[1891912439] 'applied index is now lower than readState.Index' (duration: 114.913µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:06:48.05621Z","caller":"traceutil/trace.go:171","msg":"trace[1268262077] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"187.966026ms","start":"2026-05-27T10:06:47.868222Z","end":"2026-05-27T10:06:48.056188Z","steps":["trace[1268262077] 'process raft request' (duration: 187.788051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:48.056253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.034674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:48.056278Z","caller":"traceutil/trace.go:171","msg":"trace[491850117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8414; }","duration":"177.097206ms","start":"2026-05-27T10:06:47.87917Z","end":"2026-05-27T10:06:48.056267Z","steps":["trace[491850117] 'agreement among raft nodes before linearized reading' (duration: 177.040744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:48.247386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.35235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T10:06:48.247501Z","caller":"traceutil/trace.go:171","msg":"trace[1382129466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8414; }","duration":"100.516964ms","start":"2026-05-27T10:06:48.146959Z","end":"2026-05-27T10:06:48.247475Z","steps":["trace[1382129466] 'range keys from in-memory index tree' (duration: 100.138103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.357747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.132196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:59.357842Z","caller":"traceutil/trace.go:171","msg":"trace[688081052] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8472; }","duration":"110.28154ms","start":"2026-05-27T10:06:59.247543Z","end":"2026-05-27T10:06:59.357825Z","steps":["trace[688081052] 'range keys from in-memory index tree' (duration: 110.055714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.357829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.038118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T10:06:59.357877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.29251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:59.3579Z","caller":"traceutil/trace.go:171","msg":"trace[698475399] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8472; }","duration":"155.121001ms","start":"2026-05-27T10:06:59.202761Z","end":"2026-05-27T10:06:59.357882Z","steps":["trace[698475399] 'count revisions from in-memory index tree' (duration: 154.923335ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:06:59.357929Z","caller":"traceutil/trace.go:171","msg":"trace[95929477] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8472; }","duration":"110.373513ms","start":"2026-05-27T10:06:59.247544Z","end":"2026-05-27T10:06:59.357917Z","steps":["trace[95929477] 'count revisions from in-memory index tree' (duration: 110.186868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.358027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.726865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:59.35806Z","caller":"traceutil/trace.go:171","msg":"trace[1775975793] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8472; }","duration":"109.760526ms","start":"2026-05-27T10:06:59.248291Z","end":"2026-05-27T10:06:59.358052Z","steps":["trace[1775975793] 'range keys from in-memory index tree' (duration: 109.683414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.35813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.593156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T10:06:59.358168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.303686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:59.358853Z","caller":"traceutil/trace.go:171","msg":"trace[1144124640] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8472; }","duration":"156.012146ms","start":"2026-05-27T10:06:59.202817Z","end":"2026-05-27T10:06:59.358829Z","steps":["trace[1144124640] 'range keys from in-memory index tree' (duration: 155.246534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.357736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.129774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:59.358942Z","caller":"traceutil/trace.go:171","msg":"trace[1468544668] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8472; }","duration":"129.422809ms","start":"2026-05-27T10:06:59.229488Z","end":"2026-05-27T10:06:59.358911Z","steps":["trace[1468544668] 'range keys from in-memory index tree' (duration: 128.001381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.358259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.218047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:59.359028Z","caller":"traceutil/trace.go:171","msg":"trace[1582798905] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8472; }","duration":"129.004568ms","start":"2026-05-27T10:06:59.230009Z","end":"2026-05-27T10:06:59.359013Z","steps":["trace[1582798905] 'range keys from in-memory index tree' (duration: 128.137894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.358344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.72093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:59.359063Z","caller":"traceutil/trace.go:171","msg":"trace[1987245551] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8472; }","duration":"129.223345ms","start":"2026-05-27T10:06:59.229485Z","end":"2026-05-27T10:06:59.358708Z","steps":["trace[1987245551] 'count revisions from in-memory index tree' (duration: 128.434132ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:06:59.359108Z","caller":"traceutil/trace.go:171","msg":"trace[590656560] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8472; }","duration":"155.510322ms","start":"2026-05-27T10:06:59.20359Z","end":"2026-05-27T10:06:59.3591Z","steps":["trace[590656560] 'range keys from in-memory index tree' (duration: 154.635558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.358441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.42997ms","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-05-27T10:06:59.359431Z","caller":"traceutil/trace.go:171","msg":"trace[577441846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8472; }","duration":"109.445217ms","start":"2026-05-27T10:06:59.249972Z","end":"2026-05-27T10:06:59.359417Z","steps":["trace[577441846] 'range keys from in-memory index tree' (duration: 108.295986ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:06:59.473752Z","caller":"traceutil/trace.go:171","msg":"trace[1680323406] linearizableReadLoop","detail":"{readStateIndex:8775; appliedIndex:8774; }","duration":"100.511559ms","start":"2026-05-27T10:06:59.373221Z","end":"2026-05-27T10:06:59.473733Z","steps":["trace[1680323406] 'read index received' (duration: 100.290843ms)","trace[1680323406] 'applied index is now lower than readState.Index' (duration: 219.896µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:06:59.473832Z","caller":"traceutil/trace.go:171","msg":"trace[906810540] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"110.254399ms","start":"2026-05-27T10:06:59.363537Z","end":"2026-05-27T10:06:59.473792Z","steps":["trace[906810540] 'process raft request' (duration: 110.066204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.473923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.663433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:59.474033Z","caller":"traceutil/trace.go:171","msg":"trace[735429610] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8473; }","duration":"100.826258ms","start":"2026-05-27T10:06:59.373178Z","end":"2026-05-27T10:06:59.474004Z","steps":["trace[735429610] 'agreement among raft nodes before linearized reading' (duration: 100.645383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.474127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.691495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:59.474257Z","caller":"traceutil/trace.go:171","msg":"trace[733244053] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8473; }","duration":"100.883689ms","start":"2026-05-27T10:06:59.373346Z","end":"2026-05-27T10:06:59.47423Z","steps":["trace[733244053] 'agreement among raft nodes before linearized reading' (duration: 100.590631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.474542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.764346ms","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-05-27T10:06:59.474658Z","caller":"traceutil/trace.go:171","msg":"trace[1060205703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8473; }","duration":"100.9096ms","start":"2026-05-27T10:06:59.373722Z","end":"2026-05-27T10:06:59.474631Z","steps":["trace[1060205703] 'agreement among raft nodes before linearized reading' (duration: 100.674524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.474681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.655103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:59.47476Z","caller":"traceutil/trace.go:171","msg":"trace[1371018906] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8473; }","duration":"101.573319ms","start":"2026-05-27T10:06:59.373174Z","end":"2026-05-27T10:06:59.474747Z","steps":["trace[1371018906] 'agreement among raft nodes before linearized reading' (duration: 100.666083ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:06:59.688638Z","caller":"traceutil/trace.go:171","msg":"trace[697195040] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"139.876369ms","start":"2026-05-27T10:06:59.548734Z","end":"2026-05-27T10:06:59.68861Z","steps":["trace[697195040] 'process raft request' (duration: 139.699024ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:06:59.695995Z","caller":"traceutil/trace.go:171","msg":"trace[1932171816] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"146.79399ms","start":"2026-05-27T10:06:59.549159Z","end":"2026-05-27T10:06:59.695953Z","steps":["trace[1932171816] 'process raft request' (duration: 146.543233ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:06:59.69609Z","caller":"traceutil/trace.go:171","msg":"trace[1433397022] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"119.915467ms","start":"2026-05-27T10:06:59.576118Z","end":"2026-05-27T10:06:59.696033Z","steps":["trace[1433397022] 'process raft request' (duration: 119.740572ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:06:59.695952Z","caller":"traceutil/trace.go:171","msg":"trace[351125356] linearizableReadLoop","detail":"{readStateIndex:8778; appliedIndex:8776; }","duration":"140.882527ms","start":"2026-05-27T10:06:59.555045Z","end":"2026-05-27T10:06:59.695928Z","steps":["trace[351125356] 'read index received' (duration: 133.370669ms)","trace[351125356] 'applied index is now lower than readState.Index' (duration: 7.510988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:06:59.696162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.064263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T10:06:59.696274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.151085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:59.696353Z","caller":"traceutil/trace.go:171","msg":"trace[450540504] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8477; }","duration":"141.258478ms","start":"2026-05-27T10:06:59.555073Z","end":"2026-05-27T10:06:59.696331Z","steps":["trace[450540504] 'agreement among raft nodes before linearized reading' (duration: 141.169365ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:06:59.69621Z","caller":"traceutil/trace.go:171","msg":"trace[1575425380] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8477; }","duration":"141.174275ms","start":"2026-05-27T10:06:59.55502Z","end":"2026-05-27T10:06:59.696194Z","steps":["trace[1575425380] 'agreement among raft nodes before linearized reading' (duration: 141.009191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.696465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.873647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:06:59.69656Z","caller":"traceutil/trace.go:171","msg":"trace[1357624504] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8477; }","duration":"141.008751ms","start":"2026-05-27T10:06:59.55553Z","end":"2026-05-27T10:06:59.696539Z","steps":["trace[1357624504] 'agreement among raft nodes before linearized reading' (duration: 140.835376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:06:59.696596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.409696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g89nb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-27T10:06:59.696674Z","caller":"traceutil/trace.go:171","msg":"trace[397454785] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g89nb; range_end:; response_count:1; response_revision:8477; }","duration":"135.519299ms","start":"2026-05-27T10:06:59.561137Z","end":"2026-05-27T10:06:59.696656Z","steps":["trace[397454785] 'agreement among raft nodes before linearized reading' (duration: 135.143508ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:07:01.80642Z","caller":"traceutil/trace.go:171","msg":"trace[1127801633] linearizableReadLoop","detail":"{readStateIndex:8788; appliedIndex:8787; }","duration":"105.54148ms","start":"2026-05-27T10:07:01.700853Z","end":"2026-05-27T10:07:01.806394Z","steps":["trace[1127801633] 'read index received' (duration: 105.338224ms)","trace[1127801633] 'applied index is now lower than readState.Index' (duration: 199.746µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:07:01.806522Z","caller":"traceutil/trace.go:171","msg":"trace[1835646881] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"108.407569ms","start":"2026-05-27T10:07:01.698087Z","end":"2026-05-27T10:07:01.806495Z","steps":["trace[1835646881] 'process raft request' (duration: 108.124871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:07:01.806699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.807967ms","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-05-27T10:07:01.806757Z","caller":"traceutil/trace.go:171","msg":"trace[385861873] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8486; }","duration":"105.893559ms","start":"2026-05-27T10:07:01.700848Z","end":"2026-05-27T10:07:01.806742Z","steps":["trace[385861873] 'agreement among raft nodes before linearized reading' (duration: 105.688074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:07:28.536908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.942491ms","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-05-27T10:07:28.536999Z","caller":"traceutil/trace.go:171","msg":"trace[17325743] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8682; }","duration":"550.070625ms","start":"2026-05-27T10:07:27.986911Z","end":"2026-05-27T10:07:28.536982Z","steps":["trace[17325743] 'range keys from in-memory index tree' (duration: 549.814698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:07:28.537034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:07:27.986895Z","time spent":"550.130746ms","remote":"127.0.0.1:40228","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-05-27T10:07:28.537442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.868833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g89nb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-27T10:07:28.537488Z","caller":"traceutil/trace.go:171","msg":"trace[1325770396] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g89nb; range_end:; response_count:1; response_revision:8682; }","duration":"543.941055ms","start":"2026-05-27T10:07:27.993536Z","end":"2026-05-27T10:07:28.537477Z","steps":["trace[1325770396] 'range keys from in-memory index tree' (duration: 543.716309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:07:28.537534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:07:27.993522Z","time spent":"544.003477ms","remote":"127.0.0.1:40108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-g89nb\" "} {"level":"warn","ts":"2026-05-27T10:07:28.538947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.810423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T10:07:28.539025Z","caller":"traceutil/trace.go:171","msg":"trace[50785718] linearizableReadLoop","detail":"{readStateIndex:8993; appliedIndex:8992; }","duration":"275.47909ms","start":"2026-05-27T10:07:28.263535Z","end":"2026-05-27T10:07:28.539014Z","steps":["trace[50785718] 'read index received' (duration: 145.992448ms)","trace[50785718] 'applied index is now lower than readState.Index' (duration: 129.485692ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:07:28.539109Z","caller":"traceutil/trace.go:171","msg":"trace[1021199190] transaction","detail":"{read_only:false; response_revision:8683; number_of_response:1; }","duration":"337.264969ms","start":"2026-05-27T10:07:28.201834Z","end":"2026-05-27T10:07:28.539099Z","steps":["trace[1021199190] 'process raft request' (duration: 207.786488ms)","trace[1021199190] 'compare' (duration: 128.420802ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:07:28.53917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:07:28.201805Z","time spent":"337.335051ms","remote":"127.0.0.1:40228","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-05-27T10:07:28.539428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.890991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-b686f98bf-d2k92.18b364276e4453fa\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-05-27T10:07:28.539467Z","caller":"traceutil/trace.go:171","msg":"trace[1925234510] range","detail":"{range_begin:/registry/events/openstack/barbican-api-b686f98bf-d2k92.18b364276e4453fa; range_end:; response_count:1; response_revision:8683; }","duration":"275.959213ms","start":"2026-05-27T10:07:28.263497Z","end":"2026-05-27T10:07:28.539456Z","steps":["trace[1925234510] 'agreement among raft nodes before linearized reading' (duration: 275.8299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:07:28.539718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.059694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T10:07:28.539756Z","caller":"traceutil/trace.go:171","msg":"trace[1938752131] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8683; }","duration":"217.100045ms","start":"2026-05-27T10:07:28.322647Z","end":"2026-05-27T10:07:28.539747Z","steps":["trace[1938752131] 'agreement among raft nodes before linearized reading' (duration: 216.974722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:07:28.540171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.111682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-27T10:07:28.540211Z","caller":"traceutil/trace.go:171","msg":"trace[1014739313] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8683; }","duration":"133.153683ms","start":"2026-05-27T10:07:28.407049Z","end":"2026-05-27T10:07:28.540203Z","steps":["trace[1014739313] 'agreement among raft nodes before linearized reading' (duration: 133.061571ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:07:32.735004Z","caller":"traceutil/trace.go:171","msg":"trace[116995943] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"125.82231ms","start":"2026-05-27T10:07:32.609147Z","end":"2026-05-27T10:07:32.734969Z","steps":["trace[116995943] 'process raft request' (duration: 124.702599ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:07:32.740466Z","caller":"traceutil/trace.go:171","msg":"trace[66269579] linearizableReadLoop","detail":"{readStateIndex:9019; appliedIndex:9017; }","duration":"130.193012ms","start":"2026-05-27T10:07:32.610247Z","end":"2026-05-27T10:07:32.74044Z","steps":["trace[66269579] 'read index received' (duration: 123.731583ms)","trace[66269579] 'applied index is now lower than readState.Index' (duration: 6.460439ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:07:32.740547Z","caller":"traceutil/trace.go:171","msg":"trace[1612222840] transaction","detail":"{read_only:false; response_revision:8707; number_of_response:1; }","duration":"130.606133ms","start":"2026-05-27T10:07:32.609921Z","end":"2026-05-27T10:07:32.740527Z","steps":["trace[1612222840] 'process raft request' (duration: 130.380447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:07:32.740997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.747377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228836"} {"level":"info","ts":"2026-05-27T10:07:32.74104Z","caller":"traceutil/trace.go:171","msg":"trace[1552466337] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8707; }","duration":"130.838449ms","start":"2026-05-27T10:07:32.610191Z","end":"2026-05-27T10:07:32.741029Z","steps":["trace[1552466337] 'agreement among raft nodes before linearized reading' (duration: 130.378836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:07:42.097955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.769008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T10:07:42.098255Z","caller":"traceutil/trace.go:171","msg":"trace[1101310083] linearizableReadLoop","detail":"{readStateIndex:9114; appliedIndex:9113; }","duration":"182.797997ms","start":"2026-05-27T10:07:41.915439Z","end":"2026-05-27T10:07:42.098237Z","steps":["trace[1101310083] 'read index received' (duration: 60.570216ms)","trace[1101310083] 'applied index is now lower than readState.Index' (duration: 122.226471ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:07:42.098309Z","caller":"traceutil/trace.go:171","msg":"trace[1541926153] transaction","detail":"{read_only:false; response_revision:8799; number_of_response:1; }","duration":"223.855282ms","start":"2026-05-27T10:07:41.874429Z","end":"2026-05-27T10:07:42.098284Z","steps":["trace[1541926153] 'process raft request' (duration: 101.648752ms)","trace[1541926153] 'compare' (duration: 121.657985ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:07:42.098454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.016412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-27T10:07:42.098498Z","caller":"traceutil/trace.go:171","msg":"trace[1706017538] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:8799; }","duration":"183.093875ms","start":"2026-05-27T10:07:41.915388Z","end":"2026-05-27T10:07:42.098481Z","steps":["trace[1706017538] 'agreement among raft nodes before linearized reading' (duration: 182.950561ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:07:42.722855Z","caller":"traceutil/trace.go:171","msg":"trace[1004070535] transaction","detail":"{read_only:false; response_revision:8804; number_of_response:1; }","duration":"134.577273ms","start":"2026-05-27T10:07:42.588242Z","end":"2026-05-27T10:07:42.72282Z","steps":["trace[1004070535] 'process raft request' (duration: 134.449189ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:07:42.762804Z","caller":"traceutil/trace.go:171","msg":"trace[934778962] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"126.272162ms","start":"2026-05-27T10:07:42.636306Z","end":"2026-05-27T10:07:42.762578Z","steps":["trace[934778962] 'process raft request' (duration: 126.132289ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:07:43.180286Z","caller":"traceutil/trace.go:171","msg":"trace[1553420478] transaction","detail":"{read_only:false; response_revision:8809; number_of_response:1; }","duration":"137.055992ms","start":"2026-05-27T10:07:43.043187Z","end":"2026-05-27T10:07:43.180243Z","steps":["trace[1553420478] 'process raft request' (duration: 107.112143ms)","trace[1553420478] 'compare' (duration: 29.741473ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:07:43.256851Z","caller":"traceutil/trace.go:171","msg":"trace[1696121370] transaction","detail":"{read_only:false; response_revision:8810; number_of_response:1; }","duration":"120.72452ms","start":"2026-05-27T10:07:43.136097Z","end":"2026-05-27T10:07:43.256822Z","steps":["trace[1696121370] 'process raft request' (duration: 120.556945ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:07:47.374579Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d2.wal"} {"level":"info","ts":"2026-05-27T10:09:12.404651Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c6cf68dde442ffa5","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T10:09:12.41217Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-27T10:09:12.412285Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-27T10:11:18.778474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8080} {"level":"info","ts":"2026-05-27T10:11:18.842055Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8080,"took":"61.520422ms","hash":3974405588,"current-db-size-bytes":57171968,"current-db-size":"57 MB","current-db-size-in-use-bytes":28577792,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-27T10:11:18.842127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3974405588,"revision":8080,"compact-revision":6003} {"level":"info","ts":"2026-05-27T10:14:37.841556Z","caller":"traceutil/trace.go:171","msg":"trace[279123713] transaction","detail":"{read_only:false; response_revision:11982; number_of_response:1; }","duration":"123.2589ms","start":"2026-05-27T10:14:37.718271Z","end":"2026-05-27T10:14:37.84153Z","steps":["trace[279123713] 'process raft request' (duration: 123.071915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:14:38.399723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.901522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T10:14:38.399831Z","caller":"traceutil/trace.go:171","msg":"trace[1470547627] transaction","detail":"{read_only:false; response_revision:11989; number_of_response:1; }","duration":"106.889617ms","start":"2026-05-27T10:14:38.292925Z","end":"2026-05-27T10:14:38.399814Z","steps":["trace[1470547627] 'compare' (duration: 100.787149ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:14:38.881015Z","caller":"traceutil/trace.go:171","msg":"trace[1349955460] linearizableReadLoop","detail":"{readStateIndex:12404; appliedIndex:12403; }","duration":"126.193721ms","start":"2026-05-27T10:14:38.754797Z","end":"2026-05-27T10:14:38.880991Z","steps":["trace[1349955460] 'read index received' (duration: 126.061468ms)","trace[1349955460] 'applied index is now lower than readState.Index' (duration: 131.243µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:14:38.881059Z","caller":"traceutil/trace.go:171","msg":"trace[1301581282] transaction","detail":"{read_only:false; response_revision:11990; number_of_response:1; }","duration":"152.467918ms","start":"2026-05-27T10:14:38.728558Z","end":"2026-05-27T10:14:38.881026Z","steps":["trace[1301581282] 'process raft request' (duration: 152.279663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:14:38.881216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.402297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:14:38.88127Z","caller":"traceutil/trace.go:171","msg":"trace[284024232] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:11990; }","duration":"126.51574ms","start":"2026-05-27T10:14:38.754738Z","end":"2026-05-27T10:14:38.881253Z","steps":["trace[284024232] 'agreement among raft nodes before linearized reading' (duration: 126.412467ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:14:38.915527Z","caller":"traceutil/trace.go:171","msg":"trace[2012208938] transaction","detail":"{read_only:false; response_revision:11991; number_of_response:1; }","duration":"147.976433ms","start":"2026-05-27T10:14:38.767527Z","end":"2026-05-27T10:14:38.915503Z","steps":["trace[2012208938] 'process raft request' (duration: 147.814429ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:16:18.784007Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10491} {"level":"info","ts":"2026-05-27T10:16:18.873166Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10491,"took":"86.697928ms","hash":2009306124,"current-db-size-bytes":57171968,"current-db-size":"57 MB","current-db-size-in-use-bytes":26427392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-27T10:16:18.873238Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2009306124,"revision":10491,"compact-revision":8080} {"level":"info","ts":"2026-05-27T10:20:40.33878Z","caller":"traceutil/trace.go:171","msg":"trace[768422576] transaction","detail":"{read_only:false; response_revision:14440; number_of_response:1; }","duration":"107.990553ms","start":"2026-05-27T10:20:40.230769Z","end":"2026-05-27T10:20:40.338759Z","steps":["trace[768422576] 'process raft request' (duration: 107.929601ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:20:40.338941Z","caller":"traceutil/trace.go:171","msg":"trace[1293565853] transaction","detail":"{read_only:false; response_revision:14438; number_of_response:1; }","duration":"146.854087ms","start":"2026-05-27T10:20:40.192079Z","end":"2026-05-27T10:20:40.338933Z","steps":["trace[1293565853] 'process raft request' (duration: 70.469307ms)","trace[1293565853] 'compare' (duration: 75.977329ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:20:40.338996Z","caller":"traceutil/trace.go:171","msg":"trace[665731895] transaction","detail":"{read_only:false; response_revision:14439; number_of_response:1; }","duration":"146.869087ms","start":"2026-05-27T10:20:40.192118Z","end":"2026-05-27T10:20:40.338987Z","steps":["trace[665731895] 'process raft request' (duration: 146.542728ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:20:40.339343Z","caller":"traceutil/trace.go:171","msg":"trace[184184811] transaction","detail":"{read_only:false; response_revision:14441; number_of_response:1; }","duration":"108.045674ms","start":"2026-05-27T10:20:40.230767Z","end":"2026-05-27T10:20:40.338813Z","steps":["trace[184184811] 'process raft request' (duration: 107.958062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:21:16.197146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.070687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T10:21:16.197255Z","caller":"traceutil/trace.go:171","msg":"trace[1328825257] linearizableReadLoop","detail":"{readStateIndex:15153; appliedIndex:15152; }","duration":"316.78515ms","start":"2026-05-27T10:21:15.880455Z","end":"2026-05-27T10:21:16.197241Z","steps":["trace[1328825257] 'read index received' (duration: 142.528857ms)","trace[1328825257] 'applied index is now lower than readState.Index' (duration: 174.254853ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:21:16.197276Z","caller":"traceutil/trace.go:171","msg":"trace[2088814742] transaction","detail":"{read_only:false; response_revision:14645; number_of_response:1; }","duration":"325.46398ms","start":"2026-05-27T10:21:15.871786Z","end":"2026-05-27T10:21:16.19725Z","steps":["trace[2088814742] 'process raft request' (duration: 151.202427ms)","trace[2088814742] 'compare' (duration: 173.957084ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:21:16.197351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.907673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:21:16.197386Z","caller":"traceutil/trace.go:171","msg":"trace[1805129627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14645; }","duration":"316.982975ms","start":"2026-05-27T10:21:15.880395Z","end":"2026-05-27T10:21:16.197378Z","steps":["trace[1805129627] 'agreement among raft nodes before linearized reading' (duration: 316.922504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:21:16.197412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:21:15.880365Z","time spent":"317.040337ms","remote":"127.0.0.1:39918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T10:21:16.197456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.164703ms","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-05-27T10:21:16.197491Z","caller":"traceutil/trace.go:171","msg":"trace[483789433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14645; }","duration":"152.236155ms","start":"2026-05-27T10:21:16.045245Z","end":"2026-05-27T10:21:16.197481Z","steps":["trace[483789433] 'agreement among raft nodes before linearized reading' (duration: 152.109322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:21:16.19749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:21:15.871749Z","time spent":"325.576083ms","remote":"127.0.0.1:40228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:21:18.789128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12661} {"level":"info","ts":"2026-05-27T10:21:18.858664Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12661,"took":"67.171816ms","hash":1170356666,"current-db-size-bytes":57171968,"current-db-size":"57 MB","current-db-size-in-use-bytes":27246592,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-27T10:21:18.85872Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1170356666,"revision":12661,"compact-revision":10491} {"level":"info","ts":"2026-05-27T10:21:38.774261Z","caller":"traceutil/trace.go:171","msg":"trace[124129363] transaction","detail":"{read_only:false; response_revision:14956; number_of_response:1; }","duration":"232.540953ms","start":"2026-05-27T10:21:38.541692Z","end":"2026-05-27T10:21:38.774233Z","steps":["trace[124129363] 'process raft request' (duration: 232.359548ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:21:38.775007Z","caller":"traceutil/trace.go:171","msg":"trace[1065463390] transaction","detail":"{read_only:false; response_revision:14957; number_of_response:1; }","duration":"185.843443ms","start":"2026-05-27T10:21:38.589146Z","end":"2026-05-27T10:21:38.774989Z","steps":["trace[1065463390] 'process raft request' (duration: 185.725149ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:23:03.936218Z","caller":"traceutil/trace.go:171","msg":"trace[697955520] transaction","detail":"{read_only:false; response_revision:15763; number_of_response:1; }","duration":"213.96683ms","start":"2026-05-27T10:23:03.722209Z","end":"2026-05-27T10:23:03.936175Z","steps":["trace[697955520] 'process raft request' (duration: 213.754684ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:23:04.95048Z","caller":"traceutil/trace.go:171","msg":"trace[697419641] linearizableReadLoop","detail":"{readStateIndex:16309; appliedIndex:16308; }","duration":"157.718817ms","start":"2026-05-27T10:23:04.792719Z","end":"2026-05-27T10:23:04.950437Z","steps":["trace[697419641] 'read index received' (duration: 119.913092ms)","trace[697419641] 'applied index is now lower than readState.Index' (duration: 37.804095ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:23:04.950687Z","caller":"traceutil/trace.go:171","msg":"trace[53979760] transaction","detail":"{read_only:false; response_revision:15765; number_of_response:1; }","duration":"265.312628ms","start":"2026-05-27T10:23:04.685358Z","end":"2026-05-27T10:23:04.95067Z","steps":["trace[53979760] 'process raft request' (duration: 227.257387ms)","trace[53979760] 'compare' (duration: 37.540037ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:23:04.951209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.476117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T10:23:04.951262Z","caller":"traceutil/trace.go:171","msg":"trace[868718745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15766; }","duration":"158.608841ms","start":"2026-05-27T10:23:04.79264Z","end":"2026-05-27T10:23:04.951249Z","steps":["trace[868718745] 'agreement among raft nodes before linearized reading' (duration: 158.403715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:23:04.951413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.420547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-27T10:23:04.951512Z","caller":"traceutil/trace.go:171","msg":"trace[547443354] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15766; }","duration":"124.586272ms","start":"2026-05-27T10:23:04.8269Z","end":"2026-05-27T10:23:04.951486Z","steps":["trace[547443354] 'agreement among raft nodes before linearized reading' (duration: 124.313864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:23:04.95187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.314382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:23:04.951922Z","caller":"traceutil/trace.go:171","msg":"trace[1210580925] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:15766; }","duration":"112.397644ms","start":"2026-05-27T10:23:04.839508Z","end":"2026-05-27T10:23:04.951906Z","steps":["trace[1210580925] 'agreement among raft nodes before linearized reading' (duration: 112.323862ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:23:05.061292Z","caller":"traceutil/trace.go:171","msg":"trace[1326369656] transaction","detail":"{read_only:false; response_revision:15767; number_of_response:1; }","duration":"103.037626ms","start":"2026-05-27T10:23:04.95821Z","end":"2026-05-27T10:23:05.061248Z","steps":["trace[1326369656] 'process raft request' (duration: 102.769479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:24:28.902195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.231935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:24:28.902293Z","caller":"traceutil/trace.go:171","msg":"trace[1169247777] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:16325; }","duration":"318.378779ms","start":"2026-05-27T10:24:28.583892Z","end":"2026-05-27T10:24:28.902271Z","steps":["trace[1169247777] 'count revisions from in-memory index tree' (duration: 318.149213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:24:28.902326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:24:28.583871Z","time spent":"318.4466ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-27T10:24:28.902567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.790569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-05-27T10:24:28.902597Z","caller":"traceutil/trace.go:171","msg":"trace[316681375] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16325; }","duration":"183.874931ms","start":"2026-05-27T10:24:28.718713Z","end":"2026-05-27T10:24:28.902588Z","steps":["trace[316681375] 'range keys from in-memory index tree' (duration: 183.641925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:24:28.902914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.05264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:24:28.902985Z","caller":"traceutil/trace.go:171","msg":"trace[1510603108] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:16325; }","duration":"118.184574ms","start":"2026-05-27T10:24:28.784786Z","end":"2026-05-27T10:24:28.90297Z","steps":["trace[1510603108] 'count revisions from in-memory index tree' (duration: 117.974708ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:24:29.253266Z","caller":"traceutil/trace.go:171","msg":"trace[698332432] linearizableReadLoop","detail":"{readStateIndex:16889; appliedIndex:16888; }","duration":"225.76374ms","start":"2026-05-27T10:24:29.027487Z","end":"2026-05-27T10:24:29.253251Z","steps":["trace[698332432] 'read index received' (duration: 225.571955ms)","trace[698332432] 'applied index is now lower than readState.Index' (duration: 190.965µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:24:29.253372Z","caller":"traceutil/trace.go:171","msg":"trace[686471989] transaction","detail":"{read_only:false; response_revision:16327; number_of_response:1; }","duration":"226.576632ms","start":"2026-05-27T10:24:29.026784Z","end":"2026-05-27T10:24:29.253361Z","steps":["trace[686471989] 'process raft request' (duration: 226.343086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:24:29.253607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.049909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-27T10:24:29.253654Z","caller":"traceutil/trace.go:171","msg":"trace[1292759182] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:16327; }","duration":"226.217653ms","start":"2026-05-27T10:24:29.027424Z","end":"2026-05-27T10:24:29.253642Z","steps":["trace[1292759182] 'agreement among raft nodes before linearized reading' (duration: 225.985316ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:24:30.263158Z","caller":"traceutil/trace.go:171","msg":"trace[30486452] linearizableReadLoop","detail":"{readStateIndex:16896; appliedIndex:16895; }","duration":"151.622613ms","start":"2026-05-27T10:24:30.111512Z","end":"2026-05-27T10:24:30.263135Z","steps":["trace[30486452] 'read index received' (duration: 151.49488ms)","trace[30486452] 'applied index is now lower than readState.Index' (duration: 126.793µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:24:30.263527Z","caller":"traceutil/trace.go:171","msg":"trace[673640634] transaction","detail":"{read_only:false; response_revision:16334; number_of_response:1; }","duration":"165.422159ms","start":"2026-05-27T10:24:30.098084Z","end":"2026-05-27T10:24:30.263506Z","steps":["trace[673640634] 'process raft request' (duration: 164.914725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:24:30.263905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.381675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T10:24:30.263968Z","caller":"traceutil/trace.go:171","msg":"trace[748991368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16334; }","duration":"152.481457ms","start":"2026-05-27T10:24:30.111468Z","end":"2026-05-27T10:24:30.263949Z","steps":["trace[748991368] 'agreement among raft nodes before linearized reading' (duration: 152.314303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:24:30.26397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.361026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:24:30.264102Z","caller":"traceutil/trace.go:171","msg":"trace[1809289115] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:16334; }","duration":"144.51418ms","start":"2026-05-27T10:24:30.119575Z","end":"2026-05-27T10:24:30.264089Z","steps":["trace[1809289115] 'agreement among raft nodes before linearized reading' (duration: 144.349116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:24:30.264063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.679485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-27T10:24:30.264231Z","caller":"traceutil/trace.go:171","msg":"trace[150103431] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16334; }","duration":"119.86643ms","start":"2026-05-27T10:24:30.144352Z","end":"2026-05-27T10:24:30.264218Z","steps":["trace[150103431] 'agreement among raft nodes before linearized reading' (duration: 119.645124ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:26:18.799887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14677} {"level":"info","ts":"2026-05-27T10:26:18.873799Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14677,"took":"71.121505ms","hash":3074664489,"current-db-size-bytes":57171968,"current-db-size":"57 MB","current-db-size-in-use-bytes":33755136,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-27T10:26:18.873916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3074664489,"revision":14677,"compact-revision":12661} {"level":"warn","ts":"2026-05-27T10:26:45.988267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.419472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:26:45.988329Z","caller":"traceutil/trace.go:171","msg":"trace[527523706] transaction","detail":"{read_only:false; response_revision:17473; number_of_response:1; }","duration":"114.056642ms","start":"2026-05-27T10:26:45.874252Z","end":"2026-05-27T10:26:45.988308Z","steps":["trace[527523706] 'process raft request' (duration: 84.669942ms)","trace[527523706] 'compare' (duration: 29.03437ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:26:45.988344Z","caller":"traceutil/trace.go:171","msg":"trace[302106091] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17474; }","duration":"157.516045ms","start":"2026-05-27T10:26:45.830818Z","end":"2026-05-27T10:26:45.988334Z","steps":["trace[302106091] 'agreement among raft nodes before linearized reading' (duration: 157.371341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:26:45.988329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.650506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T10:26:45.988405Z","caller":"traceutil/trace.go:171","msg":"trace[553855220] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17474; }","duration":"158.763548ms","start":"2026-05-27T10:26:45.829624Z","end":"2026-05-27T10:26:45.988387Z","steps":["trace[553855220] 'agreement among raft nodes before linearized reading' (duration: 158.556492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:26:45.988554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.080178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:26:45.988591Z","caller":"traceutil/trace.go:171","msg":"trace[2035697251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17474; }","duration":"112.1589ms","start":"2026-05-27T10:26:45.876424Z","end":"2026-05-27T10:26:45.988583Z","steps":["trace[2035697251] 'agreement among raft nodes before linearized reading' (duration: 112.093819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:26:45.988724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.378109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T10:26:45.988749Z","caller":"traceutil/trace.go:171","msg":"trace[921949588] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17474; }","duration":"154.444691ms","start":"2026-05-27T10:26:45.834298Z","end":"2026-05-27T10:26:45.988742Z","steps":["trace[921949588] 'agreement among raft nodes before linearized reading' (duration: 154.392219ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:31:18.808522Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17332} {"level":"info","ts":"2026-05-27T10:31:18.90951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17332,"took":"98.436408ms","hash":89033265,"current-db-size-bytes":57171968,"current-db-size":"57 MB","current-db-size-in-use-bytes":29761536,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-27T10:31:18.909595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":89033265,"revision":17332,"compact-revision":14677} {"level":"info","ts":"2026-05-27T10:32:03.151693Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c6cf68dde442ffa5","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T10:32:03.156228Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-27T10:32:03.156309Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-27T10:36:18.81562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19103} {"level":"info","ts":"2026-05-27T10:36:18.904528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19103,"took":"85.421498ms","hash":3505910061,"current-db-size-bytes":57171968,"current-db-size":"57 MB","current-db-size-in-use-bytes":23662592,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-27T10:36:18.904653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3505910061,"revision":19103,"compact-revision":17332}