{"level":"warn","ts":"2026-04-09T05:52:45.065304Z","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-09T05:52:45.065387Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.85: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.19.213.85:2380","--initial-cluster=instance=https://199.19.213.85:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.85:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.85: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-09T05:52:45.065559Z","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-09T05:52:45.065574Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.85:2380"]} {"level":"info","ts":"2026-04-09T05:52:45.065591Z","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-09T05:52:45.066251Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"]} {"level":"info","ts":"2026-04-09T05:52:45.066375Z","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.19.213.85:2380"],"listen-peer-urls":["https://199.19.213.85:2380"],"advertise-client-urls":["https://199.19.213.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.85: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-09T05:52:45.069963Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.348506ms"} {"level":"info","ts":"2026-04-09T05:52:45.075504Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a0c678c4e560a44","cluster-id":"dbaaf49260b7a32b"} {"level":"info","ts":"2026-04-09T05:52:45.075646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T05:52:45.075669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became follower at term 0"} {"level":"info","ts":"2026-04-09T05:52:45.075677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a0c678c4e560a44 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T05:52:45.07572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became follower at term 1"} {"level":"info","ts":"2026-04-09T05:52:45.075779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=(7641596520048233028)"} {"level":"warn","ts":"2026-04-09T05:52:45.081459Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T05:52:45.082819Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T05:52:45.083976Z","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-09T05:52:45.085953Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a0c678c4e560a44","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T05:52:45.086217Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a0c678c4e560a44","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T05:52:45.086376Z","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-09T05:52:45.086477Z","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-09T05:52:45.086519Z","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-09T05:52:45.086992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=(7641596520048233028)"} {"level":"info","ts":"2026-04-09T05:52:45.088087Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dbaaf49260b7a32b","local-member-id":"6a0c678c4e560a44","added-peer-id":"6a0c678c4e560a44","added-peer-peer-urls":["https://199.19.213.85:2380"]} {"level":"info","ts":"2026-04-09T05:52:45.093129Z","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-09T05:52:45.093246Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.85:2380"} {"level":"info","ts":"2026-04-09T05:52:45.093282Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.85:2380"} {"level":"info","ts":"2026-04-09T05:52:45.093501Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a0c678c4e560a44","initial-advertise-peer-urls":["https://199.19.213.85:2380"],"listen-peer-urls":["https://199.19.213.85:2380"],"advertise-client-urls":["https://199.19.213.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T05:52:45.093533Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T05:52:45.376271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T05:52:45.376355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T05:52:45.376397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 received MsgPreVoteResp from 6a0c678c4e560a44 at term 1"} {"level":"info","ts":"2026-04-09T05:52:45.376424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became candidate at term 2"} {"level":"info","ts":"2026-04-09T05:52:45.376436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 received MsgVoteResp from 6a0c678c4e560a44 at term 2"} {"level":"info","ts":"2026-04-09T05:52:45.376457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became leader at term 2"} {"level":"info","ts":"2026-04-09T05:52:45.376472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a0c678c4e560a44 elected leader 6a0c678c4e560a44 at term 2"} {"level":"info","ts":"2026-04-09T05:52:45.37841Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:52:45.380413Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dbaaf49260b7a32b","local-member-id":"6a0c678c4e560a44","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:52:45.38048Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:52:45.380502Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:52:45.380526Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a0c678c4e560a44","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.85:2379]}","request-path":"/0/members/6a0c678c4e560a44/attributes","cluster-id":"dbaaf49260b7a32b","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T05:52:45.380713Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:52:45.381162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:52:45.381382Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T05:52:45.381408Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T05:52:45.384172Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.85:2379"} {"level":"info","ts":"2026-04-09T05:52:45.386799Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T05:53:13.402249Z","caller":"traceutil/trace.go:171","msg":"trace[1352813390] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"137.989914ms","start":"2026-04-09T05:53:13.264238Z","end":"2026-04-09T05:53:13.402227Z","steps":["trace[1352813390] 'process raft request' (duration: 135.682404ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:13.402313Z","caller":"traceutil/trace.go:171","msg":"trace[2082110973] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"135.787325ms","start":"2026-04-09T05:53:13.266513Z","end":"2026-04-09T05:53:13.402301Z","steps":["trace[2082110973] 'process raft request' (duration: 135.118739ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:13.40225Z","caller":"traceutil/trace.go:171","msg":"trace[762363136] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"133.597784ms","start":"2026-04-09T05:53:13.268632Z","end":"2026-04-09T05:53:13.40223Z","steps":["trace[762363136] 'process raft request' (duration: 133.10059ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:13.402419Z","caller":"traceutil/trace.go:171","msg":"trace[245650730] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"133.815937ms","start":"2026-04-09T05:53:13.268597Z","end":"2026-04-09T05:53:13.402413Z","steps":["trace[245650730] 'process raft request' (duration: 133.10849ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:13.402452Z","caller":"traceutil/trace.go:171","msg":"trace[965023095] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"132.463744ms","start":"2026-04-09T05:53:13.269983Z","end":"2026-04-09T05:53:13.402447Z","steps":["trace[965023095] 'process raft request' (duration: 131.877728ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:13.402477Z","caller":"traceutil/trace.go:171","msg":"trace[989930052] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"114.261349ms","start":"2026-04-09T05:53:13.288209Z","end":"2026-04-09T05:53:13.402471Z","steps":["trace[989930052] 'process raft request' (duration: 113.744314ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:13.402496Z","caller":"traceutil/trace.go:171","msg":"trace[1622540745] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"133.732946ms","start":"2026-04-09T05:53:13.268758Z","end":"2026-04-09T05:53:13.402491Z","steps":["trace[1622540745] 'process raft request' (duration: 133.024369ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:13.402576Z","caller":"traceutil/trace.go:171","msg":"trace[899895886] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"119.524977ms","start":"2026-04-09T05:53:13.28302Z","end":"2026-04-09T05:53:13.402545Z","steps":["trace[899895886] 'process raft request' (duration: 118.87186ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:13.402682Z","caller":"traceutil/trace.go:171","msg":"trace[1235387941] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"132.728376ms","start":"2026-04-09T05:53:13.269855Z","end":"2026-04-09T05:53:13.402583Z","steps":["trace[1235387941] 'process raft request' (duration: 131.955689ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:19.666868Z","caller":"traceutil/trace.go:171","msg":"trace[991392697] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"196.285305ms","start":"2026-04-09T05:53:19.470556Z","end":"2026-04-09T05:53:19.666841Z","steps":["trace[991392697] 'read index received' (duration: 195.258116ms)","trace[991392697] 'applied index is now lower than readState.Index' (duration: 1.025939ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:53:19.667002Z","caller":"traceutil/trace.go:171","msg":"trace[250939754] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"271.253626ms","start":"2026-04-09T05:53:19.395712Z","end":"2026-04-09T05:53:19.666966Z","steps":["trace[250939754] 'process raft request' (duration: 270.493649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:19.667384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.772699ms","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-09T05:53:19.667585Z","caller":"traceutil/trace.go:171","msg":"trace[1413242195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"196.996651ms","start":"2026-04-09T05:53:19.470506Z","end":"2026-04-09T05:53:19.667503Z","steps":["trace[1413242195] 'agreement among raft nodes before linearized reading' (duration: 196.511097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:53:20.11166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.277836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cxch7\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-09T05:53:20.111756Z","caller":"traceutil/trace.go:171","msg":"trace[813263563] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cxch7; range_end:; response_count:1; response_revision:573; }","duration":"169.440298ms","start":"2026-04-09T05:53:19.942292Z","end":"2026-04-09T05:53:20.111733Z","steps":["trace[813263563] 'range keys from in-memory index tree' (duration: 169.171785ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:53:20.229043Z","caller":"traceutil/trace.go:171","msg":"trace[213907831] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"101.135125ms","start":"2026-04-09T05:53:20.12788Z","end":"2026-04-09T05:53:20.229015Z","steps":["trace[213907831] 'process raft request' (duration: 100.991394ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:07.328553Z","caller":"traceutil/trace.go:171","msg":"trace[1931614819] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"101.161832ms","start":"2026-04-09T05:54:07.227365Z","end":"2026-04-09T05:54:07.328526Z","steps":["trace[1931614819] 'process raft request' (duration: 101.005367ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:54:54.431297Z","caller":"traceutil/trace.go:171","msg":"trace[1107392276] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1886; }","duration":"107.625739ms","start":"2026-04-09T05:54:54.323645Z","end":"2026-04-09T05:54:54.431271Z","steps":["trace[1107392276] 'read index received' (duration: 82.719146ms)","trace[1107392276] 'applied index is now lower than readState.Index' (duration: 24.905583ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:54:54.431477Z","caller":"traceutil/trace.go:171","msg":"trace[1430054905] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"179.545982ms","start":"2026-04-09T05:54:54.25191Z","end":"2026-04-09T05:54:54.431456Z","steps":["trace[1430054905] 'process raft request' (duration: 154.497335ms)","trace[1430054905] 'compare' (duration: 24.760951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:54:54.431666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.012358ms","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-09T05:54:54.431742Z","caller":"traceutil/trace.go:171","msg":"trace[1690718384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1811; }","duration":"108.12251ms","start":"2026-04-09T05:54:54.323606Z","end":"2026-04-09T05:54:54.431729Z","steps":["trace[1690718384] 'agreement among raft nodes before linearized reading' (duration: 107.870024ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:55:00.590379Z","caller":"traceutil/trace.go:171","msg":"trace[1364171229] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"123.01109ms","start":"2026-04-09T05:55:00.467336Z","end":"2026-04-09T05:55:00.590347Z","steps":["trace[1364171229] 'process raft request' (duration: 122.767956ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:55:06.72895Z","caller":"traceutil/trace.go:171","msg":"trace[1262190893] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"109.148626ms","start":"2026-04-09T05:55:06.619726Z","end":"2026-04-09T05:55:06.728875Z","steps":["trace[1262190893] 'process raft request' (duration: 109.024583ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:55:06.733554Z","caller":"traceutil/trace.go:171","msg":"trace[1585762146] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"108.354901ms","start":"2026-04-09T05:55:06.625181Z","end":"2026-04-09T05:55:06.733536Z","steps":["trace[1585762146] 'read index received' (duration: 103.704414ms)","trace[1585762146] 'applied index is now lower than readState.Index' (duration: 4.649397ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:55:06.733606Z","caller":"traceutil/trace.go:171","msg":"trace[801760202] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"112.732693ms","start":"2026-04-09T05:55:06.620831Z","end":"2026-04-09T05:55:06.733564Z","steps":["trace[801760202] 'process raft request' (duration: 112.60939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:55:06.733905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.283632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-09T05:55:06.734023Z","caller":"traceutil/trace.go:171","msg":"trace[1632665530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1914; }","duration":"106.427054ms","start":"2026-04-09T05:55:06.627578Z","end":"2026-04-09T05:55:06.734005Z","steps":["trace[1632665530] 'agreement among raft nodes before linearized reading' (duration: 106.213011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:55:06.73454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.352489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-09T05:55:06.734605Z","caller":"traceutil/trace.go:171","msg":"trace[464472355] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1914; }","duration":"109.456041ms","start":"2026-04-09T05:55:06.62513Z","end":"2026-04-09T05:55:06.734586Z","steps":["trace[464472355] 'agreement among raft nodes before linearized reading' (duration: 108.484633ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:55:09.74537Z","caller":"traceutil/trace.go:171","msg":"trace[472282755] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"132.771448ms","start":"2026-04-09T05:55:09.612575Z","end":"2026-04-09T05:55:09.745346Z","steps":["trace[472282755] 'process raft request' (duration: 132.638266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:24.235615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.610635ms","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-04-09T05:56:24.235689Z","caller":"traceutil/trace.go:171","msg":"trace[1065426821] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2450; }","duration":"116.714226ms","start":"2026-04-09T05:56:24.118962Z","end":"2026-04-09T05:56:24.235676Z","steps":["trace[1065426821] 'range keys from in-memory index tree' (duration: 116.499845ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:57.038217Z","caller":"traceutil/trace.go:171","msg":"trace[388091329] transaction","detail":"{read_only:false; response_revision:2635; number_of_response:1; }","duration":"189.755973ms","start":"2026-04-09T05:56:56.848433Z","end":"2026-04-09T05:56:57.038189Z","steps":["trace[388091329] 'process raft request' (duration: 189.497279ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:56:57.478384Z","caller":"traceutil/trace.go:171","msg":"trace[20228007] transaction","detail":"{read_only:false; response_revision:2637; number_of_response:1; }","duration":"149.800565ms","start":"2026-04-09T05:56:57.328539Z","end":"2026-04-09T05:56:57.47834Z","steps":["trace[20228007] 'process raft request' (duration: 114.649773ms)","trace[20228007] 'compare' (duration: 35.03066ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:56:57.479402Z","caller":"traceutil/trace.go:171","msg":"trace[601414208] linearizableReadLoop","detail":"{readStateIndex:2740; appliedIndex:2738; }","duration":"128.169541ms","start":"2026-04-09T05:56:57.351215Z","end":"2026-04-09T05:56:57.479385Z","steps":["trace[601414208] 'read index received' (duration: 92.254081ms)","trace[601414208] 'applied index is now lower than readState.Index' (duration: 35.91483ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:56:57.479512Z","caller":"traceutil/trace.go:171","msg":"trace[1301735404] transaction","detail":"{read_only:false; response_revision:2638; number_of_response:1; }","duration":"150.916357ms","start":"2026-04-09T05:56:57.328579Z","end":"2026-04-09T05:56:57.479495Z","steps":["trace[1301735404] 'process raft request' (duration: 150.711085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:56:57.479663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.429194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-09T05:56:57.479747Z","caller":"traceutil/trace.go:171","msg":"trace[1008015253] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2638; }","duration":"128.533905ms","start":"2026-04-09T05:56:57.351191Z","end":"2026-04-09T05:56:57.479725Z","steps":["trace[1008015253] 'agreement among raft nodes before linearized reading' (duration: 128.360733ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:37.182042Z","caller":"traceutil/trace.go:171","msg":"trace[1055300396] transaction","detail":"{read_only:false; response_revision:4342; number_of_response:1; }","duration":"115.389564ms","start":"2026-04-09T06:00:37.066628Z","end":"2026-04-09T06:00:37.182018Z","steps":["trace[1055300396] 'process raft request' (duration: 115.241082ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:37.186128Z","caller":"traceutil/trace.go:171","msg":"trace[1553933090] linearizableReadLoop","detail":"{readStateIndex:4514; appliedIndex:4513; }","duration":"118.064744ms","start":"2026-04-09T06:00:37.068055Z","end":"2026-04-09T06:00:37.186119Z","steps":["trace[1553933090] 'read index received' (duration: 114.237406ms)","trace[1553933090] 'applied index is now lower than readState.Index' (duration: 3.826838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:00:37.186218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.151145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:00:37.186246Z","caller":"traceutil/trace.go:171","msg":"trace[806200067] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:0; response_revision:4343; }","duration":"118.209916ms","start":"2026-04-09T06:00:37.068026Z","end":"2026-04-09T06:00:37.186236Z","steps":["trace[806200067] 'agreement among raft nodes before linearized reading' (duration: 118.128155ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:37.18653Z","caller":"traceutil/trace.go:171","msg":"trace[224839297] transaction","detail":"{read_only:false; response_revision:4343; number_of_response:1; }","duration":"118.853506ms","start":"2026-04-09T06:00:37.06753Z","end":"2026-04-09T06:00:37.186383Z","steps":["trace[224839297] 'process raft request' (duration: 118.518291ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:47.432645Z","caller":"traceutil/trace.go:171","msg":"trace[1952969702] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"125.731319ms","start":"2026-04-09T06:00:47.306872Z","end":"2026-04-09T06:00:47.432604Z","steps":["trace[1952969702] 'process raft request' (duration: 125.378304ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:47.432818Z","caller":"traceutil/trace.go:171","msg":"trace[1032796117] transaction","detail":"{read_only:false; response_revision:4582; number_of_response:1; }","duration":"125.584466ms","start":"2026-04-09T06:00:47.307192Z","end":"2026-04-09T06:00:47.432776Z","steps":["trace[1032796117] 'process raft request' (duration: 125.245662ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:47.43296Z","caller":"traceutil/trace.go:171","msg":"trace[2142297844] linearizableReadLoop","detail":"{readStateIndex:4758; appliedIndex:4755; }","duration":"125.864491ms","start":"2026-04-09T06:00:47.307074Z","end":"2026-04-09T06:00:47.432938Z","steps":["trace[2142297844] 'read index received' (duration: 2.424346ms)","trace[2142297844] 'applied index is now lower than readState.Index' (duration: 123.439065ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:00:47.433116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.016613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:00:47.433145Z","caller":"traceutil/trace.go:171","msg":"trace[74271488] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4583; }","duration":"126.068163ms","start":"2026-04-09T06:00:47.307066Z","end":"2026-04-09T06:00:47.433135Z","steps":["trace[74271488] 'agreement among raft nodes before linearized reading' (duration: 125.972732ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:47.433267Z","caller":"traceutil/trace.go:171","msg":"trace[86161582] transaction","detail":"{read_only:false; response_revision:4583; number_of_response:1; }","duration":"125.15721ms","start":"2026-04-09T06:00:47.307999Z","end":"2026-04-09T06:00:47.433157Z","steps":["trace[86161582] 'process raft request' (duration: 124.733094ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:47.466768Z","caller":"traceutil/trace.go:171","msg":"trace[1737275094] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"120.974718ms","start":"2026-04-09T06:00:47.345769Z","end":"2026-04-09T06:00:47.466744Z","steps":["trace[1737275094] 'process raft request' (duration: 111.495908ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:47.639148Z","caller":"traceutil/trace.go:171","msg":"trace[284117977] transaction","detail":"{read_only:false; response_revision:4590; number_of_response:1; }","duration":"122.741874ms","start":"2026-04-09T06:00:47.516379Z","end":"2026-04-09T06:00:47.639121Z","steps":["trace[284117977] 'process raft request' (duration: 122.379299ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:47.639142Z","caller":"traceutil/trace.go:171","msg":"trace[945439171] transaction","detail":"{read_only:false; response_revision:4589; number_of_response:1; }","duration":"124.217436ms","start":"2026-04-09T06:00:47.514897Z","end":"2026-04-09T06:00:47.639114Z","steps":["trace[945439171] 'process raft request' (duration: 123.691398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:47.639667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.863285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9424"} {"level":"info","ts":"2026-04-09T06:00:47.639692Z","caller":"traceutil/trace.go:171","msg":"trace[2128347022] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4590; }","duration":"124.934997ms","start":"2026-04-09T06:00:47.51475Z","end":"2026-04-09T06:00:47.639685Z","steps":["trace[2128347022] 'agreement among raft nodes before linearized reading' (duration: 124.332347ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:47.783363Z","caller":"traceutil/trace.go:171","msg":"trace[1722296802] transaction","detail":"{read_only:false; response_revision:4592; number_of_response:1; }","duration":"130.115963ms","start":"2026-04-09T06:00:47.653226Z","end":"2026-04-09T06:00:47.783342Z","steps":["trace[1722296802] 'process raft request' (duration: 130.041841ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:47.783397Z","caller":"traceutil/trace.go:171","msg":"trace[857301507] transaction","detail":"{read_only:false; response_revision:4591; number_of_response:1; }","duration":"130.183535ms","start":"2026-04-09T06:00:47.653191Z","end":"2026-04-09T06:00:47.783374Z","steps":["trace[857301507] 'process raft request' (duration: 96.031577ms)","trace[857301507] 'compare' (duration: 33.925714ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:00:47.807155Z","caller":"traceutil/trace.go:171","msg":"trace[2092576948] linearizableReadLoop","detail":"{readStateIndex:4770; appliedIndex:4767; }","duration":"150.118371ms","start":"2026-04-09T06:00:47.657018Z","end":"2026-04-09T06:00:47.807136Z","steps":["trace[2092576948] 'read index received' (duration: 92.221321ms)","trace[2092576948] 'applied index is now lower than readState.Index' (duration: 57.8961ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:00:47.807257Z","caller":"traceutil/trace.go:171","msg":"trace[898923029] transaction","detail":"{read_only:false; response_revision:4593; number_of_response:1; }","duration":"152.660679ms","start":"2026-04-09T06:00:47.654585Z","end":"2026-04-09T06:00:47.807245Z","steps":["trace[898923029] 'process raft request' (duration: 152.426265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:47.80742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.384456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-04-09T06:00:47.807488Z","caller":"traceutil/trace.go:171","msg":"trace[1905240241] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4593; }","duration":"150.476607ms","start":"2026-04-09T06:00:47.657Z","end":"2026-04-09T06:00:47.807477Z","steps":["trace[1905240241] 'agreement among raft nodes before linearized reading' (duration: 150.271604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:47.820907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.982564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-09T06:00:47.820964Z","caller":"traceutil/trace.go:171","msg":"trace[1176526491] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4594; }","duration":"145.068966ms","start":"2026-04-09T06:00:47.675881Z","end":"2026-04-09T06:00:47.820949Z","steps":["trace[1176526491] 'agreement among raft nodes before linearized reading' (duration: 144.927254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:47.82173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.694984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4848"} {"level":"info","ts":"2026-04-09T06:00:47.821767Z","caller":"traceutil/trace.go:171","msg":"trace[1646737592] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4595; }","duration":"159.745474ms","start":"2026-04-09T06:00:47.662014Z","end":"2026-04-09T06:00:47.821759Z","steps":["trace[1646737592] 'agreement among raft nodes before linearized reading' (duration: 159.257247ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:50.358391Z","caller":"traceutil/trace.go:171","msg":"trace[54771097] transaction","detail":"{read_only:false; response_revision:4627; number_of_response:1; }","duration":"134.268993ms","start":"2026-04-09T06:00:50.224077Z","end":"2026-04-09T06:00:50.358346Z","steps":["trace[54771097] 'process raft request' (duration: 134.084499ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:53.340664Z","caller":"traceutil/trace.go:171","msg":"trace[574777354] linearizableReadLoop","detail":"{readStateIndex:4849; appliedIndex:4848; }","duration":"156.582169ms","start":"2026-04-09T06:00:53.183994Z","end":"2026-04-09T06:00:53.340576Z","steps":["trace[574777354] 'read index received' (duration: 156.348644ms)","trace[574777354] 'applied index is now lower than readState.Index' (duration: 232.305µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:00:53.340727Z","caller":"traceutil/trace.go:171","msg":"trace[1562009018] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"159.104105ms","start":"2026-04-09T06:00:53.18159Z","end":"2026-04-09T06:00:53.340694Z","steps":["trace[1562009018] 'process raft request' (duration: 158.76026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:53.340931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.919413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-784b67b969-xbjgg.18a49ae2d78ef33f\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-04-09T06:00:53.340993Z","caller":"traceutil/trace.go:171","msg":"trace[2053735395] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-784b67b969-xbjgg.18a49ae2d78ef33f; range_end:; response_count:1; response_revision:4671; }","duration":"157.007814ms","start":"2026-04-09T06:00:53.183961Z","end":"2026-04-09T06:00:53.340969Z","steps":["trace[2053735395] 'agreement among raft nodes before linearized reading' (duration: 156.805031ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:28.874251Z","caller":"traceutil/trace.go:171","msg":"trace[799916791] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"253.063341ms","start":"2026-04-09T06:01:28.621147Z","end":"2026-04-09T06:01:28.874211Z","steps":["trace[799916791] 'process raft request' (duration: 252.816197ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:28.875273Z","caller":"traceutil/trace.go:171","msg":"trace[1349850859] linearizableReadLoop","detail":"{readStateIndex:5257; appliedIndex:5255; }","duration":"129.009284ms","start":"2026-04-09T06:01:28.746227Z","end":"2026-04-09T06:01:28.875236Z","steps":["trace[1349850859] 'read index received' (duration: 127.663025ms)","trace[1349850859] 'applied index is now lower than readState.Index' (duration: 1.344149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:01:28.875909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.652753ms","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-09T06:01:28.875971Z","caller":"traceutil/trace.go:171","msg":"trace[905178619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5067; }","duration":"129.778505ms","start":"2026-04-09T06:01:28.746173Z","end":"2026-04-09T06:01:28.875951Z","steps":["trace[905178619] 'agreement among raft nodes before linearized reading' (duration: 129.397399ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:28.876117Z","caller":"traceutil/trace.go:171","msg":"trace[2011429097] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"150.758824ms","start":"2026-04-09T06:01:28.725326Z","end":"2026-04-09T06:01:28.876084Z","steps":["trace[2011429097] 'process raft request' (duration: 149.684269ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:45.415672Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2888} {"level":"info","ts":"2026-04-09T06:02:45.510234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2888,"took":"89.477863ms","hash":3952657893,"current-db-size-bytes":54169600,"current-db-size":"54 MB","current-db-size-in-use-bytes":29433856,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-09T06:02:45.510318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3952657893,"revision":2888,"compact-revision":-1} {"level":"info","ts":"2026-04-09T06:07:45.432686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5608} {"level":"info","ts":"2026-04-09T06:07:45.516399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5608,"took":"81.703073ms","hash":2745415393,"current-db-size-bytes":54169600,"current-db-size":"54 MB","current-db-size-in-use-bytes":18108416,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-09T06:07:45.516465Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2745415393,"revision":5608,"compact-revision":2888}