{"level":"warn","ts":"2026-08-22T14:34:32.700129Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:34:32.700224Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-08-22T14:34:32.700298Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:34:32.700308Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-08-22T14:34:32.700332Z","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-08-22T14:34:32.700969Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-08-22T14:34:32.701053Z","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":8,"max-cpu-available":8,"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.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-08-22T14:34:32.707542Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.251493ms"} {"level":"info","ts":"2026-08-22T14:34:32.713901Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-08-22T14:34:32.713968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T14:34:32.713985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-08-22T14:34:32.713995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T14:34:32.714003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-08-22T14:34:32.714033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-08-22T14:34:32.724412Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T14:34:32.725643Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T14:34:32.727075Z","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-08-22T14:34:32.729007Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T14:34:32.729256Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T14:34:32.729347Z","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-08-22T14:34:32.729409Z","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-08-22T14:34:32.729425Z","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-08-22T14:34:32.730124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-08-22T14:34:32.730315Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-08-22T14:34:32.732339Z","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-08-22T14:34:32.732459Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-08-22T14:34:32.732497Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-08-22T14:34:32.732516Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T14:34:32.732546Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T14:34:32.815039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T14:34:32.815113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T14:34:32.815159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-08-22T14:34:32.815181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-08-22T14:34:32.815192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-08-22T14:34:32.815208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-08-22T14:34:32.815227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-08-22T14:34:32.816012Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:34:32.81684Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:34:32.816838Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T14:34:32.816873Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:34:32.817435Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:34:32.817566Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:34:32.817603Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:34:32.818904Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T14:34:32.818955Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T14:34:32.822379Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T14:34:32.822475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"warn","ts":"2026-08-22T14:35:09.778952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.123685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:35:09.779366Z","caller":"traceutil/trace.go:171","msg":"trace[633578852] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"264.032313ms","start":"2026-08-22T14:35:09.515314Z","end":"2026-08-22T14:35:09.779347Z","steps":["trace[633578852] 'process raft request' (duration: 263.856326ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:09.779322Z","caller":"traceutil/trace.go:171","msg":"trace[1856034196] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"269.528852ms","start":"2026-08-22T14:35:09.509749Z","end":"2026-08-22T14:35:09.779277Z","steps":["trace[1856034196] 'process raft request' (duration: 42.317705ms)","trace[1856034196] 'compare' (duration: 225.914867ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:35:09.779922Z","caller":"traceutil/trace.go:171","msg":"trace[2063651356] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"247.798392ms","start":"2026-08-22T14:35:09.532108Z","end":"2026-08-22T14:35:09.779906Z","steps":["trace[2063651356] 'process raft request' (duration: 247.666138ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:09.780138Z","caller":"traceutil/trace.go:171","msg":"trace[1627175251] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"251.831786ms","start":"2026-08-22T14:35:09.528246Z","end":"2026-08-22T14:35:09.780077Z","steps":["trace[1627175251] 'process raft request' (duration: 251.303046ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:09.780153Z","caller":"traceutil/trace.go:171","msg":"trace[273707342] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"264.697948ms","start":"2026-08-22T14:35:09.515399Z","end":"2026-08-22T14:35:09.780097Z","steps":["trace[273707342] 'process raft request' (duration: 263.891757ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:09.819039Z","caller":"traceutil/trace.go:171","msg":"trace[1410188866] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"218.715594ms","start":"2026-08-22T14:35:09.600277Z","end":"2026-08-22T14:35:09.818993Z","steps":["trace[1410188866] 'process raft request' (duration: 218.515746ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:16.094762Z","caller":"traceutil/trace.go:171","msg":"trace[862287562] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"280.764319ms","start":"2026-08-22T14:35:15.813964Z","end":"2026-08-22T14:35:16.094728Z","steps":["trace[862287562] 'process raft request' (duration: 278.685339ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:16.100572Z","caller":"traceutil/trace.go:171","msg":"trace[1740698890] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"209.53101ms","start":"2026-08-22T14:35:15.890976Z","end":"2026-08-22T14:35:16.100507Z","steps":["trace[1740698890] 'read index received' (duration: 200.723898ms)","trace[1740698890] 'applied index is now lower than readState.Index' (duration: 8.805562ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:35:16.100808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.8089ms","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-08-22T14:35:16.100851Z","caller":"traceutil/trace.go:171","msg":"trace[553695758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"209.898853ms","start":"2026-08-22T14:35:15.890939Z","end":"2026-08-22T14:35:16.100838Z","steps":["trace[553695758] 'agreement among raft nodes before linearized reading' (duration: 209.729977ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:16.101036Z","caller":"traceutil/trace.go:171","msg":"trace[629697554] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"236.068664ms","start":"2026-08-22T14:35:15.86491Z","end":"2026-08-22T14:35:16.100979Z","steps":["trace[629697554] 'process raft request' (duration: 235.386018ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:16.309591Z","caller":"traceutil/trace.go:171","msg":"trace[379989182] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"157.364159ms","start":"2026-08-22T14:35:16.152191Z","end":"2026-08-22T14:35:16.309555Z","steps":["trace[379989182] 'process raft request' (duration: 143.504063ms)","trace[379989182] 'compare' (duration: 13.349565ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:35:16.608481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.843803ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:35:16.608577Z","caller":"traceutil/trace.go:171","msg":"trace[1502856391] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:626; }","duration":"191.948807ms","start":"2026-08-22T14:35:16.416613Z","end":"2026-08-22T14:35:16.608562Z","steps":["trace[1502856391] 'range keys from in-memory index tree' (duration: 191.831072ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:16.78531Z","caller":"traceutil/trace.go:171","msg":"trace[294320734] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"136.405548ms","start":"2026-08-22T14:35:16.648878Z","end":"2026-08-22T14:35:16.785284Z","steps":["trace[294320734] 'read index received' (duration: 102.572559ms)","trace[294320734] 'applied index is now lower than readState.Index' (duration: 33.831289ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:35:16.785698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.791943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rd5st\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-22T14:35:16.785748Z","caller":"traceutil/trace.go:171","msg":"trace[232020304] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"170.824649ms","start":"2026-08-22T14:35:16.614886Z","end":"2026-08-22T14:35:16.785711Z","steps":["trace[232020304] 'process raft request' (duration: 136.493861ms)","trace[232020304] 'compare' (duration: 33.777126ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:35:16.785745Z","caller":"traceutil/trace.go:171","msg":"trace[1695546953] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rd5st; range_end:; response_count:1; response_revision:628; }","duration":"136.894997ms","start":"2026-08-22T14:35:16.648839Z","end":"2026-08-22T14:35:16.785734Z","steps":["trace[1695546953] 'agreement among raft nodes before linearized reading' (duration: 136.554244ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:46.206063Z","caller":"traceutil/trace.go:171","msg":"trace[124212735] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"106.972665ms","start":"2026-08-22T14:36:46.099064Z","end":"2026-08-22T14:36:46.206037Z","steps":["trace[124212735] 'process raft request' (duration: 106.809437ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:46.414938Z","caller":"traceutil/trace.go:171","msg":"trace[2093589404] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"201.96787ms","start":"2026-08-22T14:36:46.212949Z","end":"2026-08-22T14:36:46.414917Z","steps":["trace[2093589404] 'process raft request' (duration: 201.804301ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:46.415315Z","caller":"traceutil/trace.go:171","msg":"trace[1662904781] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"201.535064ms","start":"2026-08-22T14:36:46.213719Z","end":"2026-08-22T14:36:46.415254Z","steps":["trace[1662904781] 'process raft request' (duration: 201.153057ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:46.415686Z","caller":"traceutil/trace.go:171","msg":"trace[1427802470] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1935; }","duration":"146.300569ms","start":"2026-08-22T14:36:46.269362Z","end":"2026-08-22T14:36:46.415663Z","steps":["trace[1427802470] 'read index received' (duration: 145.318288ms)","trace[1427802470] 'applied index is now lower than readState.Index' (duration: 981.281µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:46.41588Z","caller":"traceutil/trace.go:171","msg":"trace[1287799256] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"201.437235ms","start":"2026-08-22T14:36:46.214408Z","end":"2026-08-22T14:36:46.415845Z","steps":["trace[1287799256] 'process raft request' (duration: 201.094818ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:46.416042Z","caller":"traceutil/trace.go:171","msg":"trace[401398212] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"199.434284ms","start":"2026-08-22T14:36:46.216589Z","end":"2026-08-22T14:36:46.416023Z","steps":["trace[401398212] 'process raft request' (duration: 199.020878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:46.416185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.816073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-08-22T14:36:46.416241Z","caller":"traceutil/trace.go:171","msg":"trace[1391836177] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1869; }","duration":"146.945062ms","start":"2026-08-22T14:36:46.269279Z","end":"2026-08-22T14:36:46.416224Z","steps":["trace[1391836177] 'agreement among raft nodes before linearized reading' (duration: 146.770544ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:46.548903Z","caller":"traceutil/trace.go:171","msg":"trace[647296730] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"126.406814ms","start":"2026-08-22T14:36:46.42247Z","end":"2026-08-22T14:36:46.548877Z","steps":["trace[647296730] 'process raft request' (duration: 126.358315ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:46.549105Z","caller":"traceutil/trace.go:171","msg":"trace[1471301767] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"133.328726ms","start":"2026-08-22T14:36:46.415753Z","end":"2026-08-22T14:36:46.549082Z","steps":["trace[1471301767] 'read index received' (duration: 121.052147ms)","trace[1471301767] 'applied index is now lower than readState.Index' (duration: 12.274129ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:46.548953Z","caller":"traceutil/trace.go:171","msg":"trace[1138771367] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"205.514465ms","start":"2026-08-22T14:36:46.343398Z","end":"2026-08-22T14:36:46.548912Z","steps":["trace[1138771367] 'process raft request' (duration: 193.388614ms)","trace[1138771367] 'compare' (duration: 11.893223ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:36:46.549994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.204088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:46.550069Z","caller":"traceutil/trace.go:171","msg":"trace[1387376894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1871; }","duration":"270.338826ms","start":"2026-08-22T14:36:46.279714Z","end":"2026-08-22T14:36:46.550052Z","steps":["trace[1387376894] 'agreement among raft nodes before linearized reading' (duration: 269.448674ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:20.45022Z","caller":"traceutil/trace.go:171","msg":"trace[452743302] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"159.370051ms","start":"2026-08-22T14:37:20.290814Z","end":"2026-08-22T14:37:20.450184Z","steps":["trace[452743302] 'process raft request' (duration: 159.179601ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:20.939758Z","caller":"traceutil/trace.go:171","msg":"trace[1497557588] linearizableReadLoop","detail":"{readStateIndex:2182; appliedIndex:2181; }","duration":"160.012733ms","start":"2026-08-22T14:37:20.77972Z","end":"2026-08-22T14:37:20.939732Z","steps":["trace[1497557588] 'read index received' (duration: 71.898667ms)","trace[1497557588] 'applied index is now lower than readState.Index' (duration: 88.112866ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:20.939917Z","caller":"traceutil/trace.go:171","msg":"trace[917695586] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"160.938544ms","start":"2026-08-22T14:37:20.778937Z","end":"2026-08-22T14:37:20.939875Z","steps":["trace[917695586] 'process raft request' (duration: 72.620768ms)","trace[917695586] 'compare' (duration: 88.065846ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:37:20.940197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.449153ms","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-08-22T14:37:20.940259Z","caller":"traceutil/trace.go:171","msg":"trace[239780150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2106; }","duration":"160.562414ms","start":"2026-08-22T14:37:20.779684Z","end":"2026-08-22T14:37:20.940246Z","steps":["trace[239780150] 'agreement among raft nodes before linearized reading' (duration: 160.380543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:21.171588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.350814ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-22T14:37:21.171935Z","caller":"traceutil/trace.go:171","msg":"trace[503272619] linearizableReadLoop","detail":"{readStateIndex:2183; appliedIndex:2182; }","duration":"232.064127ms","start":"2026-08-22T14:37:20.939837Z","end":"2026-08-22T14:37:21.171901Z","steps":["trace[503272619] 'read index received' (duration: 101.506333ms)","trace[503272619] 'applied index is now lower than readState.Index' (duration: 130.552024ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:21.171997Z","caller":"traceutil/trace.go:171","msg":"trace[1087865768] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"227.640192ms","start":"2026-08-22T14:37:20.944338Z","end":"2026-08-22T14:37:21.171978Z","steps":["trace[1087865768] 'process raft request' (duration: 227.393502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:21.172355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.142955ms","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-08-22T14:37:21.172416Z","caller":"traceutil/trace.go:171","msg":"trace[1954074574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2107; }","duration":"380.239875ms","start":"2026-08-22T14:37:20.792163Z","end":"2026-08-22T14:37:21.172403Z","steps":["trace[1954074574] 'agreement among raft nodes before linearized reading' (duration: 380.062025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:37:21.172463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:37:20.792146Z","time spent":"380.303165ms","remote":"127.0.0.1:37678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T14:37:21.400427Z","caller":"traceutil/trace.go:171","msg":"trace[1792700912] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"174.59754ms","start":"2026-08-22T14:37:21.225797Z","end":"2026-08-22T14:37:21.400395Z","steps":["trace[1792700912] 'process raft request' (duration: 172.783687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:38:15.699172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.647553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-22T14:38:15.699291Z","caller":"traceutil/trace.go:171","msg":"trace[1645438386] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2508; }","duration":"104.811746ms","start":"2026-08-22T14:38:15.594459Z","end":"2026-08-22T14:38:15.699271Z","steps":["trace[1645438386] 'range keys from in-memory index tree' (duration: 104.459691ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:38:48.998772Z","caller":"traceutil/trace.go:171","msg":"trace[1774209059] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"105.923288ms","start":"2026-08-22T14:38:48.89278Z","end":"2026-08-22T14:38:48.998703Z","steps":["trace[1774209059] 'process raft request' (duration: 105.682124ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:42:47.159311Z","caller":"traceutil/trace.go:171","msg":"trace[657312054] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"189.817628ms","start":"2026-08-22T14:42:46.969471Z","end":"2026-08-22T14:42:47.159288Z","steps":["trace[657312054] 'process raft request' (duration: 180.960461ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:42:47.186247Z","caller":"traceutil/trace.go:171","msg":"trace[1696240658] linearizableReadLoop","detail":"{readStateIndex:4881; appliedIndex:4877; }","duration":"112.159781ms","start":"2026-08-22T14:42:47.074075Z","end":"2026-08-22T14:42:47.186235Z","steps":["trace[1696240658] 'read index received' (duration: 76.28012ms)","trace[1696240658] 'applied index is now lower than readState.Index' (duration: 35.878531ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:42:47.186328Z","caller":"traceutil/trace.go:171","msg":"trace[2033682105] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"212.613636ms","start":"2026-08-22T14:42:46.973703Z","end":"2026-08-22T14:42:47.186316Z","steps":["trace[2033682105] 'process raft request' (duration: 212.393139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:42:47.186795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.691829ms","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-08-22T14:42:47.186896Z","caller":"traceutil/trace.go:171","msg":"trace[1529646419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4709; }","duration":"112.809303ms","start":"2026-08-22T14:42:47.074062Z","end":"2026-08-22T14:42:47.186871Z","steps":["trace[1529646419] 'agreement among raft nodes before linearized reading' (duration: 112.503623ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:42:47.187006Z","caller":"traceutil/trace.go:171","msg":"trace[1563775250] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"173.518244ms","start":"2026-08-22T14:42:47.013468Z","end":"2026-08-22T14:42:47.186987Z","steps":["trace[1563775250] 'process raft request' (duration: 172.690296ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:42:47.187154Z","caller":"traceutil/trace.go:171","msg":"trace[305250046] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"136.312037ms","start":"2026-08-22T14:42:47.050832Z","end":"2026-08-22T14:42:47.187144Z","steps":["trace[305250046] 'process raft request' (duration: 135.365844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:42:47.187443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.802945ms","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-08-22T14:42:47.189999Z","caller":"traceutil/trace.go:171","msg":"trace[4024974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4709; }","duration":"109.971856ms","start":"2026-08-22T14:42:47.07999Z","end":"2026-08-22T14:42:47.189962Z","steps":["trace[4024974] 'agreement among raft nodes before linearized reading' (duration: 106.701432ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:42:58.974657Z","caller":"traceutil/trace.go:171","msg":"trace[690678272] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"213.831218ms","start":"2026-08-22T14:42:58.760788Z","end":"2026-08-22T14:42:58.97462Z","steps":["trace[690678272] 'process raft request' (duration: 188.93047ms)","trace[690678272] 'compare' (duration: 24.622728ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:42:59.431048Z","caller":"traceutil/trace.go:171","msg":"trace[229861290] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"138.409095ms","start":"2026-08-22T14:42:59.292603Z","end":"2026-08-22T14:42:59.431012Z","steps":["trace[229861290] 'process raft request' (duration: 66.092985ms)","trace[229861290] 'compare' (duration: 72.03224ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:42:59.431129Z","caller":"traceutil/trace.go:171","msg":"trace[6819120] linearizableReadLoop","detail":"{readStateIndex:5056; appliedIndex:5054; }","duration":"112.401328ms","start":"2026-08-22T14:42:59.318694Z","end":"2026-08-22T14:42:59.431096Z","steps":["trace[6819120] 'read index received' (duration: 39.926893ms)","trace[6819120] 'applied index is now lower than readState.Index' (duration: 72.472305ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:42:59.431113Z","caller":"traceutil/trace.go:171","msg":"trace[1079412119] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"133.317427ms","start":"2026-08-22T14:42:59.29778Z","end":"2026-08-22T14:42:59.431097Z","steps":["trace[1079412119] 'process raft request' (duration: 133.1056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:42:59.43141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.691687ms","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-08-22T14:42:59.431523Z","caller":"traceutil/trace.go:171","msg":"trace[1290808429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4878; }","duration":"112.827663ms","start":"2026-08-22T14:42:59.318679Z","end":"2026-08-22T14:42:59.431506Z","steps":["trace[1290808429] 'agreement among raft nodes before linearized reading' (duration: 112.508881ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:44:33.049595Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2925} {"level":"info","ts":"2026-08-22T14:44:33.124813Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2925,"took":"73.747838ms","hash":1136340374,"current-db-size-bytes":52731904,"current-db-size":"53 MB","current-db-size-in-use-bytes":29376512,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-22T14:44:33.124908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1136340374,"revision":2925,"compact-revision":-1} {"level":"info","ts":"2026-08-22T14:46:15.935409Z","caller":"traceutil/trace.go:171","msg":"trace[1831797689] transaction","detail":"{read_only:false; response_revision:6318; number_of_response:1; }","duration":"107.577495ms","start":"2026-08-22T14:46:15.827798Z","end":"2026-08-22T14:46:15.935375Z","steps":["trace[1831797689] 'process raft request' (duration: 50.780428ms)","trace[1831797689] 'compare' (duration: 56.590886ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:46:33.22136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.738218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:46:33.221811Z","caller":"traceutil/trace.go:171","msg":"trace[1470255764] transaction","detail":"{read_only:false; response_revision:6455; number_of_response:1; }","duration":"115.720244ms","start":"2026-08-22T14:46:33.106068Z","end":"2026-08-22T14:46:33.221789Z","steps":["trace[1470255764] 'process raft request' (duration: 115.616543ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:46:33.22189Z","caller":"traceutil/trace.go:171","msg":"trace[1763640526] transaction","detail":"{read_only:false; response_revision:6454; number_of_response:1; }","duration":"151.437313ms","start":"2026-08-22T14:46:33.070413Z","end":"2026-08-22T14:46:33.22185Z","steps":["trace[1763640526] 'process raft request' (duration: 36.121371ms)","trace[1763640526] 'compare' (duration: 114.631248ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:46:38.548843Z","caller":"traceutil/trace.go:171","msg":"trace[265718814] transaction","detail":"{read_only:false; response_revision:6487; number_of_response:1; }","duration":"126.736288ms","start":"2026-08-22T14:46:38.422078Z","end":"2026-08-22T14:46:38.548814Z","steps":["trace[265718814] 'process raft request' (duration: 126.512797ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:49:33.05568Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5644} {"level":"info","ts":"2026-08-22T14:49:33.127273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5644,"took":"69.273029ms","hash":1066592713,"current-db-size-bytes":52731904,"current-db-size":"53 MB","current-db-size-in-use-bytes":18370560,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-22T14:49:33.127325Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1066592713,"revision":5644,"compact-revision":2925}