{"level":"warn","ts":"2026-04-13T17:38:33.489422Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T17:38:33.489544Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-04-13T17:38:33.489632Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T17:38:33.489646Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-13T17:38:33.489668Z","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-04-13T17:38:33.490377Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-13T17:38:33.490463Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-04-13T17:38:33.495159Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.405076ms"} {"level":"info","ts":"2026-04-13T17:38:33.503707Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-13T17:38:33.503909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T17:38:33.503973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-13T17:38:33.503999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T17:38:33.504012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-13T17:38:33.504545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-13T17:38:33.515494Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T17:38:33.517063Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T17:38:33.518138Z","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-04-13T17:38:33.52044Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T17:38:33.520719Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T17:38:33.520711Z","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-04-13T17:38:33.52082Z","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-04-13T17:38:33.520832Z","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-04-13T17:38:33.523394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-13T17:38:33.52356Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-13T17:38:33.525403Z","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-04-13T17:38:33.525677Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T17:38:33.525712Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T17:38:33.525794Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-13T17:38:33.525841Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-13T17:38:33.605785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T17:38:33.605868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T17:38:33.605903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-13T17:38:33.605925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-13T17:38:33.605944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-13T17:38:33.60596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-13T17:38:33.605974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-13T17:38:33.607073Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:38:33.607418Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T17:38:33.607468Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:38:33.607434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:38:33.60792Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T17:38:33.608047Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T17:38:33.608113Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:38:33.608243Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:38:33.608292Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:38:33.611736Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-04-13T17:38:33.612124Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T17:38:54.981261Z","caller":"traceutil/trace.go:171","msg":"trace[1015156345] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"210.587848ms","start":"2026-04-13T17:38:54.770645Z","end":"2026-04-13T17:38:54.981233Z","steps":["trace[1015156345] 'process raft request' (duration: 210.3766ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:39:03.655391Z","caller":"traceutil/trace.go:171","msg":"trace[610584215] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"180.870783ms","start":"2026-04-13T17:39:03.474496Z","end":"2026-04-13T17:39:03.655367Z","steps":["trace[610584215] 'process raft request' (duration: 180.652735ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:39:03.668457Z","caller":"traceutil/trace.go:171","msg":"trace[253390521] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"143.947574ms","start":"2026-04-13T17:39:03.524485Z","end":"2026-04-13T17:39:03.668433Z","steps":["trace[253390521] 'process raft request' (duration: 143.862041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:39:04.172672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.044989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bcd2g\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-13T17:39:04.176092Z","caller":"traceutil/trace.go:171","msg":"trace[2131312825] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bcd2g; range_end:; response_count:1; response_revision:599; }","duration":"412.243486ms","start":"2026-04-13T17:39:03.760543Z","end":"2026-04-13T17:39:04.172786Z","steps":["trace[2131312825] 'range keys from in-memory index tree' (duration: 411.884344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:39:04.176232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:39:03.760531Z","time spent":"415.684937ms","remote":"127.0.0.1:34384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-bcd2g\" "} {"level":"warn","ts":"2026-04-13T17:39:04.176019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.705423ms","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-04-13T17:39:04.179727Z","caller":"traceutil/trace.go:171","msg":"trace[426259448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"286.478075ms","start":"2026-04-13T17:39:03.893209Z","end":"2026-04-13T17:39:04.179687Z","steps":["trace[426259448] 'range keys from in-memory index tree' (duration: 279.440807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:40:17.207487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.529998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:40:17.207713Z","caller":"traceutil/trace.go:171","msg":"trace[1602761424] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"217.794148ms","start":"2026-04-13T17:40:16.989872Z","end":"2026-04-13T17:40:17.207666Z","steps":["trace[1602761424] 'process raft request' (duration: 83.470987ms)","trace[1602761424] 'compare' (duration: 133.341655ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:40:17.207782Z","caller":"traceutil/trace.go:171","msg":"trace[953676867] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"105.746946ms","start":"2026-04-13T17:40:17.102002Z","end":"2026-04-13T17:40:17.207749Z","steps":["trace[953676867] 'process raft request' (duration: 105.653474ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:40:17.207957Z","caller":"traceutil/trace.go:171","msg":"trace[1990484126] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"213.959543ms","start":"2026-04-13T17:40:16.993963Z","end":"2026-04-13T17:40:17.207922Z","steps":["trace[1990484126] 'read index received' (duration: 79.280837ms)","trace[1990484126] 'applied index is now lower than readState.Index' (duration: 134.674906ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:40:17.20816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.208708ms","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-04-13T17:40:17.20821Z","caller":"traceutil/trace.go:171","msg":"trace[110075154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1056; }","duration":"214.289908ms","start":"2026-04-13T17:40:16.993902Z","end":"2026-04-13T17:40:17.208192Z","steps":["trace[110075154] 'agreement among raft nodes before linearized reading' (duration: 214.174126ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:40:38.169675Z","caller":"traceutil/trace.go:171","msg":"trace[224624557] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1168; }","duration":"105.305948ms","start":"2026-04-13T17:40:38.064346Z","end":"2026-04-13T17:40:38.169652Z","steps":["trace[224624557] 'read index received' (duration: 105.138786ms)","trace[224624557] 'applied index is now lower than readState.Index' (duration: 166.312µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:40:38.169723Z","caller":"traceutil/trace.go:171","msg":"trace[204639552] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"107.70142ms","start":"2026-04-13T17:40:38.061998Z","end":"2026-04-13T17:40:38.169699Z","steps":["trace[204639552] 'process raft request' (duration: 107.521009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:40:38.169932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.56048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-13T17:40:38.169999Z","caller":"traceutil/trace.go:171","msg":"trace[117401133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1125; }","duration":"105.665351ms","start":"2026-04-13T17:40:38.064319Z","end":"2026-04-13T17:40:38.169984Z","steps":["trace[117401133] 'agreement among raft nodes before linearized reading' (duration: 105.412909ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:40:38.449195Z","caller":"traceutil/trace.go:171","msg":"trace[406387805] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"276.145705ms","start":"2026-04-13T17:40:38.173022Z","end":"2026-04-13T17:40:38.449168Z","steps":["trace[406387805] 'process raft request' (duration: 222.078503ms)","trace[406387805] 'compare' (duration: 53.82006ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:41:03.586094Z","caller":"traceutil/trace.go:171","msg":"trace[73768860] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"153.716348ms","start":"2026-04-13T17:41:03.432351Z","end":"2026-04-13T17:41:03.586068Z","steps":["trace[73768860] 'read index received' (duration: 153.427875ms)","trace[73768860] 'applied index is now lower than readState.Index' (duration: 287.313µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:41:03.586187Z","caller":"traceutil/trace.go:171","msg":"trace[907247311] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"219.529499ms","start":"2026-04-13T17:41:03.366631Z","end":"2026-04-13T17:41:03.58616Z","steps":["trace[907247311] 'process raft request' (duration: 219.232046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:41:03.586345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.9743ms","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-04-13T17:41:03.586406Z","caller":"traceutil/trace.go:171","msg":"trace[1471134976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1208; }","duration":"154.088461ms","start":"2026-04-13T17:41:03.432299Z","end":"2026-04-13T17:41:03.586387Z","steps":["trace[1471134976] 'agreement among raft nodes before linearized reading' (duration: 153.892789ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:45:33.906979Z","caller":"traceutil/trace.go:171","msg":"trace[1138120813] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"196.303837ms","start":"2026-04-13T17:45:33.710618Z","end":"2026-04-13T17:45:33.906922Z","steps":["trace[1138120813] 'process raft request' (duration: 196.015281ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:45:34.066019Z","caller":"traceutil/trace.go:171","msg":"trace[934524894] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"119.023095ms","start":"2026-04-13T17:45:33.946967Z","end":"2026-04-13T17:45:34.06599Z","steps":["trace[934524894] 'process raft request' (duration: 75.707718ms)","trace[934524894] 'compare' (duration: 43.200365ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:45:45.238153Z","caller":"traceutil/trace.go:171","msg":"trace[1044335829] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"258.013572ms","start":"2026-04-13T17:45:44.980109Z","end":"2026-04-13T17:45:45.238122Z","steps":["trace[1044335829] 'process raft request' (duration: 257.822648ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:45:45.26179Z","caller":"traceutil/trace.go:171","msg":"trace[1113451672] linearizableReadLoop","detail":"{readStateIndex:2893; appliedIndex:2891; }","duration":"246.772489ms","start":"2026-04-13T17:45:45.014947Z","end":"2026-04-13T17:45:45.261719Z","steps":["trace[1113451672] 'read index received' (duration: 223.028396ms)","trace[1113451672] 'applied index is now lower than readState.Index' (duration: 23.742603ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:45:45.261833Z","caller":"traceutil/trace.go:171","msg":"trace[1161788255] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"279.923746ms","start":"2026-04-13T17:45:44.981873Z","end":"2026-04-13T17:45:45.261797Z","steps":["trace[1161788255] 'process raft request' (duration: 279.679361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:45:45.262041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.054405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:45:45.262101Z","caller":"traceutil/trace.go:171","msg":"trace[719081333] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2782; }","duration":"247.200338ms","start":"2026-04-13T17:45:45.014887Z","end":"2026-04-13T17:45:45.262088Z","steps":["trace[719081333] 'agreement among raft nodes before linearized reading' (duration: 247.063035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:45:45.262209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.671528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-7qdnz\" ","response":"range_response_count:1 size:4178"} {"level":"info","ts":"2026-04-13T17:45:45.262259Z","caller":"traceutil/trace.go:171","msg":"trace[729073816] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-patch-7qdnz; range_end:; response_count:1; response_revision:2782; }","duration":"100.77566ms","start":"2026-04-13T17:45:45.161466Z","end":"2026-04-13T17:45:45.262241Z","steps":["trace[729073816] 'agreement among raft nodes before linearized reading' (duration: 100.569956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:45:45.262467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.427712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:45:45.262506Z","caller":"traceutil/trace.go:171","msg":"trace[1493143787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2782; }","duration":"100.506585ms","start":"2026-04-13T17:45:45.16199Z","end":"2026-04-13T17:45:45.262496Z","steps":["trace[1493143787] 'agreement among raft nodes before linearized reading' (duration: 100.413222ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:45:49.441606Z","caller":"traceutil/trace.go:171","msg":"trace[722160938] linearizableReadLoop","detail":"{readStateIndex:2924; appliedIndex:2923; }","duration":"157.348724ms","start":"2026-04-13T17:45:49.284217Z","end":"2026-04-13T17:45:49.441565Z","steps":["trace[722160938] 'read index received' (duration: 30.306045ms)","trace[722160938] 'applied index is now lower than readState.Index' (duration: 127.040579ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:45:49.441654Z","caller":"traceutil/trace.go:171","msg":"trace[1765851676] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"172.857679ms","start":"2026-04-13T17:45:49.268763Z","end":"2026-04-13T17:45:49.44162Z","steps":["trace[1765851676] 'process raft request' (duration: 139.786244ms)","trace[1765851676] 'compare' (duration: 32.804389ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:45:49.441923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.66915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-13T17:45:49.441942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.906304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ingress-nginx-admission\" ","response":"range_response_count:1 size:1224"} {"level":"info","ts":"2026-04-13T17:45:49.441987Z","caller":"traceutil/trace.go:171","msg":"trace[1635447638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2812; }","duration":"157.783303ms","start":"2026-04-13T17:45:49.284188Z","end":"2026-04-13T17:45:49.441972Z","steps":["trace[1635447638] 'agreement among raft nodes before linearized reading' (duration: 157.525518ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:45:49.442034Z","caller":"traceutil/trace.go:171","msg":"trace[680246689] range","detail":"{range_begin:/registry/clusterrolebindings/ingress-nginx-admission; range_end:; response_count:1; response_revision:2812; }","duration":"114.082587ms","start":"2026-04-13T17:45:49.327931Z","end":"2026-04-13T17:45:49.442014Z","steps":["trace[680246689] 'agreement among raft nodes before linearized reading' (duration: 113.875573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:46:15.724034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.303477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T17:46:15.724167Z","caller":"traceutil/trace.go:171","msg":"trace[1393063338] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3680; }","duration":"154.521351ms","start":"2026-04-13T17:46:15.569618Z","end":"2026-04-13T17:46:15.724139Z","steps":["trace[1393063338] 'range keys from in-memory index tree' (duration: 153.9798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:46:15.724169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.525656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-13T17:46:15.724302Z","caller":"traceutil/trace.go:171","msg":"trace[781387937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3680; }","duration":"136.732491ms","start":"2026-04-13T17:46:15.587539Z","end":"2026-04-13T17:46:15.724272Z","steps":["trace[781387937] 'range keys from in-memory index tree' (duration: 136.2056ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:46:17.037995Z","caller":"traceutil/trace.go:171","msg":"trace[1523385121] transaction","detail":"{read_only:false; response_revision:3691; number_of_response:1; }","duration":"287.391429ms","start":"2026-04-13T17:46:16.750571Z","end":"2026-04-13T17:46:17.037962Z","steps":["trace[1523385121] 'process raft request' (duration: 287.156324ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:46:17.078826Z","caller":"traceutil/trace.go:171","msg":"trace[470816176] transaction","detail":"{read_only:false; response_revision:3692; number_of_response:1; }","duration":"120.551486ms","start":"2026-04-13T17:46:16.958237Z","end":"2026-04-13T17:46:17.078789Z","steps":["trace[470816176] 'process raft request' (duration: 120.330021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:46:17.202716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.594531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b87d3e82-4a2b-4254-b33c-5f949a9e6c1f\" ","response":"range_response_count:1 size:3606"} {"level":"warn","ts":"2026-04-13T17:46:17.202741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.174233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ec62855-0632-4318-a13b-db8e2ea9b645\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-04-13T17:46:17.202798Z","caller":"traceutil/trace.go:171","msg":"trace[892707239] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b87d3e82-4a2b-4254-b33c-5f949a9e6c1f; range_end:; response_count:1; response_revision:3694; }","duration":"109.734664ms","start":"2026-04-13T17:46:17.093048Z","end":"2026-04-13T17:46:17.202782Z","steps":["trace[892707239] 'agreement among raft nodes before linearized reading' (duration: 109.089321ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:46:17.202838Z","caller":"traceutil/trace.go:171","msg":"trace[1780022879] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ec62855-0632-4318-a13b-db8e2ea9b645; range_end:; response_count:1; response_revision:3694; }","duration":"110.344007ms","start":"2026-04-13T17:46:17.092469Z","end":"2026-04-13T17:46:17.202813Z","steps":["trace[1780022879] 'agreement among raft nodes before linearized reading' (duration: 109.683813ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:46:17.665823Z","caller":"traceutil/trace.go:171","msg":"trace[595046576] transaction","detail":"{read_only:false; response_revision:3695; number_of_response:1; }","duration":"334.310882ms","start":"2026-04-13T17:46:17.331038Z","end":"2026-04-13T17:46:17.665349Z","steps":["trace[595046576] 'process raft request' (duration: 334.096348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:46:17.666208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:46:17.331003Z","time spent":"335.011717ms","remote":"127.0.0.1:34372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14622,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:46:18.479657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.081753ms","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-04-13T17:46:18.479753Z","caller":"traceutil/trace.go:171","msg":"trace[543868149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3699; }","duration":"140.239106ms","start":"2026-04-13T17:46:18.339492Z","end":"2026-04-13T17:46:18.479731Z","steps":["trace[543868149] 'range keys from in-memory index tree' (duration: 139.796507ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:46:18.896214Z","caller":"traceutil/trace.go:171","msg":"trace[1621070549] linearizableReadLoop","detail":"{readStateIndex:3858; appliedIndex:3857; }","duration":"125.578473ms","start":"2026-04-13T17:46:18.770602Z","end":"2026-04-13T17:46:18.89618Z","steps":["trace[1621070549] 'read index received' (duration: 125.339648ms)","trace[1621070549] 'applied index is now lower than readState.Index' (duration: 237.235µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:46:18.896594Z","caller":"traceutil/trace.go:171","msg":"trace[1151734872] transaction","detail":"{read_only:false; response_revision:3701; number_of_response:1; }","duration":"204.868717ms","start":"2026-04-13T17:46:18.691692Z","end":"2026-04-13T17:46:18.896561Z","steps":["trace[1151734872] 'process raft request' (duration: 204.256174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:46:18.8968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.086924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T17:46:18.896857Z","caller":"traceutil/trace.go:171","msg":"trace[675586804] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3701; }","duration":"126.301368ms","start":"2026-04-13T17:46:18.770538Z","end":"2026-04-13T17:46:18.89684Z","steps":["trace[675586804] 'agreement among raft nodes before linearized reading' (duration: 125.948051ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:46:38.41352Z","caller":"traceutil/trace.go:171","msg":"trace[1888391166] transaction","detail":"{read_only:false; response_revision:4039; number_of_response:1; }","duration":"123.483348ms","start":"2026-04-13T17:46:38.29001Z","end":"2026-04-13T17:46:38.413493Z","steps":["trace[1888391166] 'process raft request' (duration: 123.307274ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:47:17.514324Z","caller":"traceutil/trace.go:171","msg":"trace[2045007489] transaction","detail":"{read_only:false; response_revision:4284; number_of_response:1; }","duration":"138.059464ms","start":"2026-04-13T17:47:17.376232Z","end":"2026-04-13T17:47:17.514291Z","steps":["trace[2045007489] 'process raft request' (duration: 137.85474ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:47:17.760203Z","caller":"traceutil/trace.go:171","msg":"trace[2010693704] linearizableReadLoop","detail":"{readStateIndex:4456; appliedIndex:4454; }","duration":"356.169547ms","start":"2026-04-13T17:47:17.403997Z","end":"2026-04-13T17:47:17.760166Z","steps":["trace[2010693704] 'read index received' (duration: 110.141459ms)","trace[2010693704] 'applied index is now lower than readState.Index' (duration: 246.026518ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:47:17.760312Z","caller":"traceutil/trace.go:171","msg":"trace[2072777657] transaction","detail":"{read_only:false; response_revision:4285; number_of_response:1; }","duration":"380.064135ms","start":"2026-04-13T17:47:17.380206Z","end":"2026-04-13T17:47:17.760271Z","steps":["trace[2072777657] 'process raft request' (duration: 282.827332ms)","trace[2072777657] 'compare' (duration: 96.972528ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:47:17.760925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:47:17.380187Z","time spent":"380.652888ms","remote":"127.0.0.1:34482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:47:17.760559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.527764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T17:47:17.76153Z","caller":"traceutil/trace.go:171","msg":"trace[133083294] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4285; }","duration":"357.484436ms","start":"2026-04-13T17:47:17.403954Z","end":"2026-04-13T17:47:17.761438Z","steps":["trace[133083294] 'agreement among raft nodes before linearized reading' (duration: 356.389922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:47:17.761816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:47:17.403939Z","time spent":"357.771801ms","remote":"127.0.0.1:50590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-13T17:48:25.876379Z","caller":"traceutil/trace.go:171","msg":"trace[1178817295] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"109.141456ms","start":"2026-04-13T17:48:25.767215Z","end":"2026-04-13T17:48:25.876356Z","steps":["trace[1178817295] 'process raft request' (duration: 108.987233ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:48:26.135853Z","caller":"traceutil/trace.go:171","msg":"trace[568560636] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"102.999655ms","start":"2026-04-13T17:48:26.032819Z","end":"2026-04-13T17:48:26.135818Z","steps":["trace[568560636] 'process raft request' (duration: 102.820672ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:48:33.999328Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1702} {"level":"info","ts":"2026-04-13T17:48:34.041048Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1702,"took":"39.809348ms","hash":795957329,"current-db-size-bytes":41652224,"current-db-size":"42 MB","current-db-size-in-use-bytes":23396352,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-13T17:48:34.041117Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":795957329,"revision":1702,"compact-revision":-1} {"level":"info","ts":"2026-04-13T17:50:45.872461Z","caller":"traceutil/trace.go:171","msg":"trace[938436177] transaction","detail":"{read_only:false; response_revision:5658; number_of_response:1; }","duration":"125.748187ms","start":"2026-04-13T17:50:45.746673Z","end":"2026-04-13T17:50:45.872421Z","steps":["trace[938436177] 'process raft request' (duration: 125.121183ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:50:45.885865Z","caller":"traceutil/trace.go:171","msg":"trace[1313416637] transaction","detail":"{read_only:false; response_revision:5659; number_of_response:1; }","duration":"124.064881ms","start":"2026-04-13T17:50:45.761774Z","end":"2026-04-13T17:50:45.885839Z","steps":["trace[1313416637] 'process raft request' (duration: 123.855056ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:52:26.697656Z","caller":"traceutil/trace.go:171","msg":"trace[1586354077] transaction","detail":"{read_only:false; response_revision:7019; number_of_response:1; }","duration":"151.163303ms","start":"2026-04-13T17:52:26.546463Z","end":"2026-04-13T17:52:26.697627Z","steps":["trace[1586354077] 'process raft request' (duration: 150.943349ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:52:26.806117Z","caller":"traceutil/trace.go:171","msg":"trace[1896823620] linearizableReadLoop","detail":"{readStateIndex:7284; appliedIndex:7283; }","duration":"101.404896ms","start":"2026-04-13T17:52:26.704674Z","end":"2026-04-13T17:52:26.806079Z","steps":["trace[1896823620] 'read index received' (duration: 63.677493ms)","trace[1896823620] 'applied index is now lower than readState.Index' (duration: 37.726053ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:52:26.806464Z","caller":"traceutil/trace.go:171","msg":"trace[2055314139] transaction","detail":"{read_only:false; response_revision:7020; number_of_response:1; }","duration":"154.924714ms","start":"2026-04-13T17:52:26.651524Z","end":"2026-04-13T17:52:26.806448Z","steps":["trace[2055314139] 'process raft request' (duration: 116.838684ms)","trace[2055314139] 'compare' (duration: 37.541549ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:52:26.80687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.168441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-47e7481f-5fef-41d7-92cb-8e1cfaf27d0a\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-04-13T17:52:26.806907Z","caller":"traceutil/trace.go:171","msg":"trace[1965082410] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-47e7481f-5fef-41d7-92cb-8e1cfaf27d0a; range_end:; response_count:1; response_revision:7020; }","duration":"102.261084ms","start":"2026-04-13T17:52:26.704633Z","end":"2026-04-13T17:52:26.806894Z","steps":["trace[1965082410] 'agreement among raft nodes before linearized reading' (duration: 102.038819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:52:26.814195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.401026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-13T17:52:26.814221Z","caller":"traceutil/trace.go:171","msg":"trace[1832694853] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:7021; }","duration":"109.544399ms","start":"2026-04-13T17:52:26.70467Z","end":"2026-04-13T17:52:26.814215Z","steps":["trace[1832694853] 'agreement among raft nodes before linearized reading' (duration: 109.444636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:52:26.814703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.534299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:15 size:102915"} {"level":"info","ts":"2026-04-13T17:52:26.814795Z","caller":"traceutil/trace.go:171","msg":"trace[2104058996] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:15; response_revision:7021; }","duration":"109.689372ms","start":"2026-04-13T17:52:26.705087Z","end":"2026-04-13T17:52:26.814776Z","steps":["trace[2104058996] 'agreement among raft nodes before linearized reading' (duration: 109.219672ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:53:09.118123Z","caller":"traceutil/trace.go:171","msg":"trace[1373697835] linearizableReadLoop","detail":"{readStateIndex:7672; appliedIndex:7671; }","duration":"104.784258ms","start":"2026-04-13T17:53:09.013312Z","end":"2026-04-13T17:53:09.118096Z","steps":["trace[1373697835] 'read index received' (duration: 104.531142ms)","trace[1373697835] 'applied index is now lower than readState.Index' (duration: 251.766µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:53:09.118201Z","caller":"traceutil/trace.go:171","msg":"trace[1688306738] transaction","detail":"{read_only:false; response_revision:7393; number_of_response:1; }","duration":"141.264183ms","start":"2026-04-13T17:53:08.9769Z","end":"2026-04-13T17:53:09.118165Z","steps":["trace[1688306738] 'process raft request' (duration: 140.953517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:53:09.118335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.992163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:53:09.118398Z","caller":"traceutil/trace.go:171","msg":"trace[119569422] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:7393; }","duration":"105.129606ms","start":"2026-04-13T17:53:09.013255Z","end":"2026-04-13T17:53:09.118385Z","steps":["trace[119569422] 'agreement among raft nodes before linearized reading' (duration: 105.006883ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:53:09.337735Z","caller":"traceutil/trace.go:171","msg":"trace[2049495214] transaction","detail":"{read_only:false; response_revision:7394; number_of_response:1; }","duration":"207.307758ms","start":"2026-04-13T17:53:09.130375Z","end":"2026-04-13T17:53:09.337683Z","steps":["trace[2049495214] 'process raft request' (duration: 205.697344ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:53:09.337761Z","caller":"traceutil/trace.go:171","msg":"trace[1024742669] transaction","detail":"{read_only:false; response_revision:7395; number_of_response:1; }","duration":"144.183845ms","start":"2026-04-13T17:53:09.193541Z","end":"2026-04-13T17:53:09.337725Z","steps":["trace[1024742669] 'process raft request' (duration: 144.112053ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:53:09.337789Z","caller":"traceutil/trace.go:171","msg":"trace[832675133] linearizableReadLoop","detail":"{readStateIndex:7673; appliedIndex:7672; }","duration":"170.490765ms","start":"2026-04-13T17:53:09.167246Z","end":"2026-04-13T17:53:09.337737Z","steps":["trace[832675133] 'read index received' (duration: 168.968013ms)","trace[832675133] 'applied index is now lower than readState.Index' (duration: 1.518432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:53:09.338054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.792052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xjddx\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-04-13T17:53:09.338117Z","caller":"traceutil/trace.go:171","msg":"trace[31333308] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xjddx; range_end:; response_count:1; response_revision:7395; }","duration":"170.908714ms","start":"2026-04-13T17:53:09.167192Z","end":"2026-04-13T17:53:09.338101Z","steps":["trace[31333308] 'agreement among raft nodes before linearized reading' (duration: 170.71921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:53:09.338205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.134161ms","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-04-13T17:53:09.338278Z","caller":"traceutil/trace.go:171","msg":"trace[2034922248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7395; }","duration":"142.278925ms","start":"2026-04-13T17:53:09.195981Z","end":"2026-04-13T17:53:09.33826Z","steps":["trace[2034922248] 'agreement among raft nodes before linearized reading' (duration: 142.003348ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:53:34.006404Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4782} {"level":"info","ts":"2026-04-13T17:53:34.092336Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4782,"took":"83.428597ms","hash":2285851124,"current-db-size-bytes":46383104,"current-db-size":"46 MB","current-db-size-in-use-bytes":33636352,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-13T17:53:34.092466Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2285851124,"revision":4782,"compact-revision":1702} {"level":"info","ts":"2026-04-13T17:54:28.816063Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000227a.wal"} {"level":"info","ts":"2026-04-13T17:54:37.613227Z","caller":"traceutil/trace.go:171","msg":"trace[1848689915] transaction","detail":"{read_only:false; response_revision:8680; number_of_response:1; }","duration":"104.494816ms","start":"2026-04-13T17:54:37.508696Z","end":"2026-04-13T17:54:37.61319Z","steps":["trace[1848689915] 'process raft request' (duration: 27.25551ms)","trace[1848689915] 'compare' (duration: 77.060513ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:55:02.853157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.809441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-barbican\" ","response":"range_response_count:1 size:3315"} {"level":"info","ts":"2026-04-13T17:55:02.853301Z","caller":"traceutil/trace.go:171","msg":"trace[113511276] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-barbican; range_end:; response_count:1; response_revision:9656; }","duration":"111.022526ms","start":"2026-04-13T17:55:02.74224Z","end":"2026-04-13T17:55:02.853263Z","steps":["trace[113511276] 'range keys from in-memory index tree' (duration: 110.564306ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:55:03.844993Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-13T17:55:03.849713Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-13T17:55:03.849798Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-13T17:55:06.070504Z","caller":"traceutil/trace.go:171","msg":"trace[1024069938] transaction","detail":"{read_only:false; response_revision:9713; number_of_response:1; }","duration":"128.719212ms","start":"2026-04-13T17:55:05.941756Z","end":"2026-04-13T17:55:06.070475Z","steps":["trace[1024069938] 'process raft request' (duration: 113.10934ms)","trace[1024069938] 'compare' (duration: 15.391158ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:55:09.173516Z","caller":"traceutil/trace.go:171","msg":"trace[1323208800] linearizableReadLoop","detail":"{readStateIndex:10088; appliedIndex:10088; }","duration":"104.805394ms","start":"2026-04-13T17:55:09.068673Z","end":"2026-04-13T17:55:09.173478Z","steps":["trace[1323208800] 'read index received' (duration: 104.792193ms)","trace[1323208800] 'applied index is now lower than readState.Index' (duration: 10.08µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:55:09.173488Z","caller":"traceutil/trace.go:171","msg":"trace[872448682] transaction","detail":"{read_only:false; response_revision:9764; number_of_response:1; }","duration":"104.882845ms","start":"2026-04-13T17:55:09.06858Z","end":"2026-04-13T17:55:09.173462Z","steps":["trace[872448682] 'process raft request' (duration: 104.664191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:09.174023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.16526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:4954"} {"level":"info","ts":"2026-04-13T17:55:09.174087Z","caller":"traceutil/trace.go:171","msg":"trace[111059253] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:9764; }","duration":"105.298113ms","start":"2026-04-13T17:55:09.068771Z","end":"2026-04-13T17:55:09.174069Z","steps":["trace[111059253] 'agreement among raft nodes before linearized reading' (duration: 104.871024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:09.174192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.498579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:09.17425Z","caller":"traceutil/trace.go:171","msg":"trace[403592845] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:9764; }","duration":"105.61397ms","start":"2026-04-13T17:55:09.068619Z","end":"2026-04-13T17:55:09.174233Z","steps":["trace[403592845] 'agreement among raft nodes before linearized reading' (duration: 105.514368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:09.174401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.430346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:09.174435Z","caller":"traceutil/trace.go:171","msg":"trace[1595707420] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:9764; }","duration":"105.545318ms","start":"2026-04-13T17:55:09.06888Z","end":"2026-04-13T17:55:09.174425Z","steps":["trace[1595707420] 'agreement among raft nodes before linearized reading' (duration: 105.495727ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:55:17.727272Z","caller":"traceutil/trace.go:171","msg":"trace[1290071873] linearizableReadLoop","detail":"{readStateIndex:10169; appliedIndex:10168; }","duration":"113.071139ms","start":"2026-04-13T17:55:17.61418Z","end":"2026-04-13T17:55:17.727251Z","steps":["trace[1290071873] 'read index received' (duration: 113.001717ms)","trace[1290071873] 'applied index is now lower than readState.Index' (duration: 68.402µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:55:17.727322Z","caller":"traceutil/trace.go:171","msg":"trace[1232528105] transaction","detail":"{read_only:false; response_revision:9843; number_of_response:1; }","duration":"212.490188ms","start":"2026-04-13T17:55:17.514804Z","end":"2026-04-13T17:55:17.727294Z","steps":["trace[1232528105] 'process raft request' (duration: 212.286424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:17.727404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.210912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:17.72743Z","caller":"traceutil/trace.go:171","msg":"trace[755050807] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:9843; }","duration":"113.305924ms","start":"2026-04-13T17:55:17.614117Z","end":"2026-04-13T17:55:17.727423Z","steps":["trace[755050807] 'agreement among raft nodes before linearized reading' (duration: 113.245642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:17.732942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.223058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:17.733037Z","caller":"traceutil/trace.go:171","msg":"trace[1807565520] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:9844; }","duration":"118.367192ms","start":"2026-04-13T17:55:17.614651Z","end":"2026-04-13T17:55:17.733018Z","steps":["trace[1807565520] 'agreement among raft nodes before linearized reading' (duration: 118.201108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:17.769046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.745276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:17.769145Z","caller":"traceutil/trace.go:171","msg":"trace[1212853275] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:9844; }","duration":"153.880468ms","start":"2026-04-13T17:55:17.615241Z","end":"2026-04-13T17:55:17.769122Z","steps":["trace[1212853275] 'agreement among raft nodes before linearized reading' (duration: 118.040654ms)","trace[1212853275] 'range keys from in-memory index tree' (duration: 35.707912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:55:17.769388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.455381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:17.76951Z","caller":"traceutil/trace.go:171","msg":"trace[1705462946] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:9844; }","duration":"154.766258ms","start":"2026-04-13T17:55:17.614711Z","end":"2026-04-13T17:55:17.769478Z","steps":["trace[1705462946] 'agreement among raft nodes before linearized reading' (duration: 118.597477ms)","trace[1705462946] 'range keys from in-memory index tree' (duration: 35.845364ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:55:17.769422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.774946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:1 size:4839"} {"level":"info","ts":"2026-04-13T17:55:17.769658Z","caller":"traceutil/trace.go:171","msg":"trace[1198623384] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:1; response_revision:9844; }","duration":"154.017372ms","start":"2026-04-13T17:55:17.615607Z","end":"2026-04-13T17:55:17.769625Z","steps":["trace[1198623384] 'agreement among raft nodes before linearized reading' (duration: 117.694338ms)","trace[1198623384] 'range keys from in-memory index tree' (duration: 35.944316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:55:25.95392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.108502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:55:25.954023Z","caller":"traceutil/trace.go:171","msg":"trace[862079095] linearizableReadLoop","detail":"{readStateIndex:10291; appliedIndex:10290; }","duration":"166.732633ms","start":"2026-04-13T17:55:25.787276Z","end":"2026-04-13T17:55:25.954008Z","steps":["trace[862079095] 'read index received' (duration: 44.403086ms)","trace[862079095] 'applied index is now lower than readState.Index' (duration: 122.328707ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:55:25.954095Z","caller":"traceutil/trace.go:171","msg":"trace[588151873] transaction","detail":"{read_only:false; response_revision:9963; number_of_response:1; }","duration":"179.124277ms","start":"2026-04-13T17:55:25.774964Z","end":"2026-04-13T17:55:25.954089Z","steps":["trace[588151873] 'process raft request' (duration: 56.767469ms)","trace[588151873] 'compare' (duration: 121.891178ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:55:25.954188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.284191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:25.954288Z","caller":"traceutil/trace.go:171","msg":"trace[339374702] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:9963; }","duration":"164.459314ms","start":"2026-04-13T17:55:25.789806Z","end":"2026-04-13T17:55:25.954265Z","steps":["trace[339374702] 'agreement among raft nodes before linearized reading' (duration: 164.308101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:25.954324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.893125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:25.954347Z","caller":"traceutil/trace.go:171","msg":"trace[899544477] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:9963; }","duration":"158.934216ms","start":"2026-04-13T17:55:25.795407Z","end":"2026-04-13T17:55:25.954341Z","steps":["trace[899544477] 'agreement among raft nodes before linearized reading' (duration: 158.884795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:25.95442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.156071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:25.954435Z","caller":"traceutil/trace.go:171","msg":"trace[1824689241] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:9963; }","duration":"167.210383ms","start":"2026-04-13T17:55:25.78722Z","end":"2026-04-13T17:55:25.954431Z","steps":["trace[1824689241] 'agreement among raft nodes before linearized reading' (duration: 167.181362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:25.954536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.403706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:55:25.954573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.134071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:25.954575Z","caller":"traceutil/trace.go:171","msg":"trace[330915180] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:9963; }","duration":"159.491218ms","start":"2026-04-13T17:55:25.795074Z","end":"2026-04-13T17:55:25.954565Z","steps":["trace[330915180] 'agreement among raft nodes before linearized reading' (duration: 159.437086ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:55:25.954588Z","caller":"traceutil/trace.go:171","msg":"trace[176880029] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:9963; }","duration":"159.164491ms","start":"2026-04-13T17:55:25.795419Z","end":"2026-04-13T17:55:25.954584Z","steps":["trace[176880029] 'agreement among raft nodes before linearized reading' (duration: 159.13956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:25.954671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.959718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:25.954697Z","caller":"traceutil/trace.go:171","msg":"trace[2044930459] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:9963; }","duration":"160.000349ms","start":"2026-04-13T17:55:25.794689Z","end":"2026-04-13T17:55:25.954689Z","steps":["trace[2044930459] 'agreement among raft nodes before linearized reading' (duration: 159.965088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:25.954767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.004683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:25.954796Z","caller":"traceutil/trace.go:171","msg":"trace[758490995] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:9963; }","duration":"163.055324ms","start":"2026-04-13T17:55:25.791733Z","end":"2026-04-13T17:55:25.954789Z","steps":["trace[758490995] 'agreement among raft nodes before linearized reading' (duration: 163.018743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:25.954548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.921706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"warn","ts":"2026-04-13T17:55:25.954888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.212797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:25.954903Z","caller":"traceutil/trace.go:171","msg":"trace[567790551] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:9963; }","duration":"159.285303ms","start":"2026-04-13T17:55:25.795608Z","end":"2026-04-13T17:55:25.954893Z","steps":["trace[567790551] 'agreement among raft nodes before linearized reading' (duration: 158.862145ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:55:25.954917Z","caller":"traceutil/trace.go:171","msg":"trace[76030611] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:9963; }","duration":"163.34295ms","start":"2026-04-13T17:55:25.791564Z","end":"2026-04-13T17:55:25.954907Z","steps":["trace[76030611] 'agreement among raft nodes before linearized reading' (duration: 163.284518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:25.954927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.621187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-04-13T17:55:25.954944Z","caller":"traceutil/trace.go:171","msg":"trace[960461837] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:9963; }","duration":"164.669757ms","start":"2026-04-13T17:55:25.790269Z","end":"2026-04-13T17:55:25.954939Z","steps":["trace[960461837] 'agreement among raft nodes before linearized reading' (duration: 164.620976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:25.955104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.131388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:25.955201Z","caller":"traceutil/trace.go:171","msg":"trace[419858636] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:9963; }","duration":"165.263261ms","start":"2026-04-13T17:55:25.789916Z","end":"2026-04-13T17:55:25.95518Z","steps":["trace[419858636] 'agreement among raft nodes before linearized reading' (duration: 164.994096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:25.955252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.329563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-04-13T17:55:25.955548Z","caller":"traceutil/trace.go:171","msg":"trace[1224969633] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:9963; }","duration":"105.491727ms","start":"2026-04-13T17:55:25.849847Z","end":"2026-04-13T17:55:25.955339Z","steps":["trace[1224969633] 'agreement among raft nodes before linearized reading' (duration: 104.629439ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:55:26.103562Z","caller":"traceutil/trace.go:171","msg":"trace[1557523758] transaction","detail":"{read_only:false; response_revision:9964; number_of_response:1; }","duration":"142.211799ms","start":"2026-04-13T17:55:25.961316Z","end":"2026-04-13T17:55:26.103528Z","steps":["trace[1557523758] 'process raft request' (duration: 107.859938ms)","trace[1557523758] 'compare' (duration: 34.211328ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:55:26.135657Z","caller":"traceutil/trace.go:171","msg":"trace[435552957] linearizableReadLoop","detail":"{readStateIndex:10293; appliedIndex:10291; }","duration":"166.742474ms","start":"2026-04-13T17:55:25.968885Z","end":"2026-04-13T17:55:26.135627Z","steps":["trace[435552957] 'read index received' (duration: 100.4014ms)","trace[435552957] 'applied index is now lower than readState.Index' (duration: 66.340054ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:55:26.135705Z","caller":"traceutil/trace.go:171","msg":"trace[625904092] transaction","detail":"{read_only:false; response_revision:9965; number_of_response:1; }","duration":"171.298569ms","start":"2026-04-13T17:55:25.964369Z","end":"2026-04-13T17:55:26.135667Z","steps":["trace[625904092] 'process raft request' (duration: 171.059535ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:55:26.135749Z","caller":"traceutil/trace.go:171","msg":"trace[410719512] transaction","detail":"{read_only:false; response_revision:9966; number_of_response:1; }","duration":"166.788864ms","start":"2026-04-13T17:55:25.968914Z","end":"2026-04-13T17:55:26.135703Z","steps":["trace[410719512] 'process raft request' (duration: 166.671882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:26.135876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.959418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:26.135908Z","caller":"traceutil/trace.go:171","msg":"trace[1002580901] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:9966; }","duration":"167.051429ms","start":"2026-04-13T17:55:25.968847Z","end":"2026-04-13T17:55:26.135899Z","steps":["trace[1002580901] 'agreement among raft nodes before linearized reading' (duration: 166.933877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:26.13593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.952307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:26.136011Z","caller":"traceutil/trace.go:171","msg":"trace[702938522] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:9966; }","duration":"167.06757ms","start":"2026-04-13T17:55:25.968925Z","end":"2026-04-13T17:55:26.135993Z","steps":["trace[702938522] 'agreement among raft nodes before linearized reading' (duration: 166.926537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:26.136055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.441286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"warn","ts":"2026-04-13T17:55:26.136084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.164836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-b4fff\" ","response":"range_response_count:1 size:15339"} {"level":"info","ts":"2026-04-13T17:55:26.136133Z","caller":"traceutil/trace.go:171","msg":"trace[1835984403] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:9966; }","duration":"166.541728ms","start":"2026-04-13T17:55:25.969573Z","end":"2026-04-13T17:55:26.136115Z","steps":["trace[1835984403] 'agreement among raft nodes before linearized reading' (duration: 166.289193ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:55:26.136137Z","caller":"traceutil/trace.go:171","msg":"trace[1665877212] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-b4fff; range_end:; response_count:1; response_revision:9966; }","duration":"102.268378ms","start":"2026-04-13T17:55:26.033853Z","end":"2026-04-13T17:55:26.136122Z","steps":["trace[1665877212] 'agreement among raft nodes before linearized reading' (duration: 102.111305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:26.13614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.10252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:26.136257Z","caller":"traceutil/trace.go:171","msg":"trace[1704370321] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:9966; }","duration":"167.255094ms","start":"2026-04-13T17:55:25.968971Z","end":"2026-04-13T17:55:26.136227Z","steps":["trace[1704370321] 'agreement among raft nodes before linearized reading' (duration: 167.04234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:26.136148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.150812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:26.136414Z","caller":"traceutil/trace.go:171","msg":"trace[987673451] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:9966; }","duration":"167.441408ms","start":"2026-04-13T17:55:25.968924Z","end":"2026-04-13T17:55:26.136366Z","steps":["trace[987673451] 'agreement among raft nodes before linearized reading' (duration: 167.086911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:26.13667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.550834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-5h24p\" ","response":"range_response_count:1 size:15348"} {"level":"info","ts":"2026-04-13T17:55:26.136746Z","caller":"traceutil/trace.go:171","msg":"trace[1334779025] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-vpn-agent-default-5h24p; range_end:; response_count:1; response_revision:9966; }","duration":"102.663437ms","start":"2026-04-13T17:55:26.034068Z","end":"2026-04-13T17:55:26.136732Z","steps":["trace[1334779025] 'agreement among raft nodes before linearized reading' (duration: 101.86621ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:55:26.288131Z","caller":"traceutil/trace.go:171","msg":"trace[1508322697] linearizableReadLoop","detail":"{readStateIndex:10295; appliedIndex:10294; }","duration":"132.323619ms","start":"2026-04-13T17:55:26.155778Z","end":"2026-04-13T17:55:26.288101Z","steps":["trace[1508322697] 'read index received' (duration: 88.24794ms)","trace[1508322697] 'applied index is now lower than readState.Index' (duration: 44.074059ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:55:26.288553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.717137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:26.288591Z","caller":"traceutil/trace.go:171","msg":"trace[1965486628] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:9967; }","duration":"131.798888ms","start":"2026-04-13T17:55:26.156784Z","end":"2026-04-13T17:55:26.288583Z","steps":["trace[1965486628] 'agreement among raft nodes before linearized reading' (duration: 131.709546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:26.288606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.542303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:26.288693Z","caller":"traceutil/trace.go:171","msg":"trace[298504871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9967; }","duration":"124.639306ms","start":"2026-04-13T17:55:26.164024Z","end":"2026-04-13T17:55:26.288663Z","steps":["trace[298504871] 'agreement among raft nodes before linearized reading' (duration: 124.504593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:26.288641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.834129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-04-13T17:55:26.28864Z","caller":"traceutil/trace.go:171","msg":"trace[716429266] transaction","detail":"{read_only:false; response_revision:9967; number_of_response:1; }","duration":"132.928381ms","start":"2026-04-13T17:55:26.155674Z","end":"2026-04-13T17:55:26.288602Z","steps":["trace[716429266] 'process raft request' (duration: 88.321952ms)","trace[716429266] 'compare' (duration: 43.774292ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:55:26.288768Z","caller":"traceutil/trace.go:171","msg":"trace[1106708273] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:9967; }","duration":"133.026623ms","start":"2026-04-13T17:55:26.15572Z","end":"2026-04-13T17:55:26.288746Z","steps":["trace[1106708273] 'agreement among raft nodes before linearized reading' (duration: 132.690016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:26.288835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.110129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:55:26.288878Z","caller":"traceutil/trace.go:171","msg":"trace[88285472] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:9967; }","duration":"127.14921ms","start":"2026-04-13T17:55:26.161711Z","end":"2026-04-13T17:55:26.28886Z","steps":["trace[88285472] 'agreement among raft nodes before linearized reading' (duration: 127.094989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:26.289137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.395715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-04-13T17:55:26.289242Z","caller":"traceutil/trace.go:171","msg":"trace[136807928] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:9967; }","duration":"127.529447ms","start":"2026-04-13T17:55:26.161687Z","end":"2026-04-13T17:55:26.289216Z","steps":["trace[136807928] 'agreement among raft nodes before linearized reading' (duration: 127.18742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:55:26.289213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.315331ms","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-04-13T17:55:26.289291Z","caller":"traceutil/trace.go:171","msg":"trace[1964760941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9967; }","duration":"126.440193ms","start":"2026-04-13T17:55:26.162834Z","end":"2026-04-13T17:55:26.289274Z","steps":["trace[1964760941] 'agreement among raft nodes before linearized reading' (duration: 126.133797ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:55:39.599517Z","caller":"traceutil/trace.go:171","msg":"trace[934523092] linearizableReadLoop","detail":"{readStateIndex:10406; appliedIndex:10405; }","duration":"249.286904ms","start":"2026-04-13T17:55:39.350199Z","end":"2026-04-13T17:55:39.599486Z","steps":["trace[934523092] 'read index received' (duration: 230.067305ms)","trace[934523092] 'applied index is now lower than readState.Index' (duration: 19.218229ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:55:39.599541Z","caller":"traceutil/trace.go:171","msg":"trace[724811229] transaction","detail":"{read_only:false; response_revision:10075; number_of_response:1; }","duration":"249.817405ms","start":"2026-04-13T17:55:39.349692Z","end":"2026-04-13T17:55:39.599509Z","steps":["trace[724811229] 'process raft request' (duration: 230.559975ms)","trace[724811229] 'compare' (duration: 18.998035ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:55:39.599788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.819265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T17:55:39.599811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.530939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-user\" ","response":"range_response_count:1 size:7366"} {"level":"info","ts":"2026-04-13T17:55:39.599891Z","caller":"traceutil/trace.go:171","msg":"trace[1187316069] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:10075; }","duration":"139.983888ms","start":"2026-04-13T17:55:39.459882Z","end":"2026-04-13T17:55:39.599865Z","steps":["trace[1187316069] 'agreement among raft nodes before linearized reading' (duration: 139.791974ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:55:39.599918Z","caller":"traceutil/trace.go:171","msg":"trace[635105697] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-user; range_end:; response_count:1; response_revision:10075; }","duration":"249.727403ms","start":"2026-04-13T17:55:39.350168Z","end":"2026-04-13T17:55:39.599895Z","steps":["trace[635105697] 'agreement among raft nodes before linearized reading' (duration: 249.416597ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:58:34.012717Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7530} {"level":"info","ts":"2026-04-13T17:58:34.119922Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7530,"took":"104.019583ms","hash":2700204123,"current-db-size-bytes":57229312,"current-db-size":"57 MB","current-db-size-in-use-bytes":38842368,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-04-13T17:58:34.120017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2700204123,"revision":7530,"compact-revision":4782} {"level":"info","ts":"2026-04-13T18:03:34.020601Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11868} {"level":"info","ts":"2026-04-13T18:03:34.155284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11868,"took":"131.298707ms","hash":2697406489,"current-db-size-bytes":57229312,"current-db-size":"57 MB","current-db-size-in-use-bytes":24371200,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-13T18:03:34.15535Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2697406489,"revision":11868,"compact-revision":7530}