{"level":"warn","ts":"2026-04-08T22:56:15.127765Z","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-08T22:56:15.127886Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-08T22:56:15.127991Z","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-08T22:56:15.128014Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-08T22:56:15.128047Z","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-08T22:56:15.129241Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-04-08T22:56:15.129365Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-08T22:56:15.134666Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.92459ms"} {"level":"info","ts":"2026-04-08T22:56:15.144116Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-04-08T22:56:15.144221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T22:56:15.144258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-04-08T22:56:15.14428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T22:56:15.144304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-04-08T22:56:15.144361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-04-08T22:56:15.15922Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T22:56:15.161118Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T22:56:15.162946Z","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-08T22:56:15.165937Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T22:56:15.166064Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T22:56:15.166153Z","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-08T22:56:15.166309Z","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-08T22:56:15.166335Z","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-08T22:56:15.167311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-04-08T22:56:15.16797Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-08T22:56:15.173283Z","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-08T22:56:15.173612Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T22:56:15.173664Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T22:56:15.173767Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-08T22:56:15.173821Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-08T22:56:15.744702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T22:56:15.744784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T22:56:15.744811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-04-08T22:56:15.744827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-04-08T22:56:15.744834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-08T22:56:15.744849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-04-08T22:56:15.744859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-08T22:56:15.745811Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:56:15.7466Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:56:15.746595Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T22:56:15.746668Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:56:15.747125Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T22:56:15.74721Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T22:56:15.747236Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:56:15.747332Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:56:15.747364Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:56:15.749747Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T22:56:15.750614Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-04-08T22:56:41.704948Z","caller":"traceutil/trace.go:171","msg":"trace[672747733] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"124.533112ms","start":"2026-04-08T22:56:41.580363Z","end":"2026-04-08T22:56:41.704896Z","steps":["trace[672747733] 'process raft request' (duration: 124.080523ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:41.869953Z","caller":"traceutil/trace.go:171","msg":"trace[1505860136] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"132.671729ms","start":"2026-04-08T22:56:41.737249Z","end":"2026-04-08T22:56:41.869921Z","steps":["trace[1505860136] 'read index received' (duration: 132.561628ms)","trace[1505860136] 'applied index is now lower than readState.Index' (duration: 109.001µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:56:41.870044Z","caller":"traceutil/trace.go:171","msg":"trace[1239646800] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"161.548125ms","start":"2026-04-08T22:56:41.708439Z","end":"2026-04-08T22:56:41.869987Z","steps":["trace[1239646800] 'process raft request' (duration: 161.30832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:56:41.870191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.890184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-04-08T22:56:41.870276Z","caller":"traceutil/trace.go:171","msg":"trace[2138413580] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:432; }","duration":"133.066157ms","start":"2026-04-08T22:56:41.737192Z","end":"2026-04-08T22:56:41.870258Z","steps":["trace[2138413580] 'agreement among raft nodes before linearized reading' (duration: 132.856674ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:56:50.167583Z","caller":"traceutil/trace.go:171","msg":"trace[1949678573] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"261.938578ms","start":"2026-04-08T22:56:49.9056Z","end":"2026-04-08T22:56:50.167538Z","steps":["trace[1949678573] 'process raft request' (duration: 223.670193ms)","trace[1949678573] 'compare' (duration: 37.690134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:57:06.227812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.323092ms","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-08T22:57:06.227997Z","caller":"traceutil/trace.go:171","msg":"trace[1742276664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:746; }","duration":"220.539026ms","start":"2026-04-08T22:57:06.007425Z","end":"2026-04-08T22:57:06.227964Z","steps":["trace[1742276664] 'range keys from in-memory index tree' (duration: 220.18403ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:57:54.22201Z","caller":"traceutil/trace.go:171","msg":"trace[1365243643] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"140.06375ms","start":"2026-04-08T22:57:54.081927Z","end":"2026-04-08T22:57:54.221991Z","steps":["trace[1365243643] 'process raft request' (duration: 139.918886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:58:17.671294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.023001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:58:17.67138Z","caller":"traceutil/trace.go:171","msg":"trace[687092317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1795; }","duration":"123.185355ms","start":"2026-04-08T22:58:17.54818Z","end":"2026-04-08T22:58:17.671365Z","steps":["trace[687092317] 'range keys from in-memory index tree' (duration: 122.908279ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:58:32.487984Z","caller":"traceutil/trace.go:171","msg":"trace[1521474772] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"103.68855ms","start":"2026-04-08T22:58:32.384274Z","end":"2026-04-08T22:58:32.487962Z","steps":["trace[1521474772] 'process raft request' (duration: 103.509167ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:06.154905Z","caller":"traceutil/trace.go:171","msg":"trace[1375808929] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2192; }","duration":"164.164291ms","start":"2026-04-08T22:59:05.990723Z","end":"2026-04-08T22:59:06.154887Z","steps":["trace[1375808929] 'read index received' (duration: 163.972318ms)","trace[1375808929] 'applied index is now lower than readState.Index' (duration: 191.213µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:06.155018Z","caller":"traceutil/trace.go:171","msg":"trace[814913372] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"169.97297ms","start":"2026-04-08T22:59:05.984989Z","end":"2026-04-08T22:59:06.154962Z","steps":["trace[814913372] 'process raft request' (duration: 169.771456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:59:06.155081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.341434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-04-08T22:59:06.155161Z","caller":"traceutil/trace.go:171","msg":"trace[603405701] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2109; }","duration":"164.469338ms","start":"2026-04-08T22:59:05.990682Z","end":"2026-04-08T22:59:06.155151Z","steps":["trace[603405701] 'agreement among raft nodes before linearized reading' (duration: 164.280674ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:59:06.440878Z","caller":"traceutil/trace.go:171","msg":"trace[1745559698] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2193; }","duration":"129.922302ms","start":"2026-04-08T22:59:06.310933Z","end":"2026-04-08T22:59:06.440856Z","steps":["trace[1745559698] 'read index received' (duration: 80.974026ms)","trace[1745559698] 'applied index is now lower than readState.Index' (duration: 48.946926ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:59:06.441055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.103485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-08T22:59:06.441027Z","caller":"traceutil/trace.go:171","msg":"trace[25872218] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"166.271872ms","start":"2026-04-08T22:59:06.27473Z","end":"2026-04-08T22:59:06.441002Z","steps":["trace[25872218] 'process raft request' (duration: 117.246065ms)","trace[25872218] 'compare' (duration: 48.776473ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:59:06.441102Z","caller":"traceutil/trace.go:171","msg":"trace[595657719] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2110; }","duration":"130.199687ms","start":"2026-04-08T22:59:06.310887Z","end":"2026-04-08T22:59:06.441087Z","steps":["trace[595657719] 'agreement among raft nodes before linearized reading' (duration: 130.058854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:00:21.437087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.43849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:00:21.437198Z","caller":"traceutil/trace.go:171","msg":"trace[635030011] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"137.821008ms","start":"2026-04-08T23:00:21.29936Z","end":"2026-04-08T23:00:21.437181Z","steps":["trace[635030011] 'compare' (duration: 130.332868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:00:21.744566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.382485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:00:21.744735Z","caller":"traceutil/trace.go:171","msg":"trace[1075545940] linearizableReadLoop","detail":"{readStateIndex:2824; appliedIndex:2823; }","duration":"198.407822ms","start":"2026-04-08T23:00:21.546309Z","end":"2026-04-08T23:00:21.744717Z","steps":["trace[1075545940] 'read index received' (duration: 41.803272ms)","trace[1075545940] 'applied index is now lower than readState.Index' (duration: 156.60295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:00:21.74483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.510983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:00:21.744936Z","caller":"traceutil/trace.go:171","msg":"trace[223299509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2716; }","duration":"198.651926ms","start":"2026-04-08T23:00:21.546253Z","end":"2026-04-08T23:00:21.744905Z","steps":["trace[223299509] 'agreement among raft nodes before linearized reading' (duration: 198.516724ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:00:21.744809Z","caller":"traceutil/trace.go:171","msg":"trace[170098813] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"221.061585ms","start":"2026-04-08T23:00:21.523684Z","end":"2026-04-08T23:00:21.744746Z","steps":["trace[170098813] 'process raft request' (duration: 64.410755ms)","trace[170098813] 'compare' (duration: 156.281903ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:04:21.399377Z","caller":"traceutil/trace.go:171","msg":"trace[870816834] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"111.547471ms","start":"2026-04-08T23:04:21.287804Z","end":"2026-04-08T23:04:21.399352Z","steps":["trace[870816834] 'process raft request' (duration: 111.347846ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:04:21.481288Z","caller":"traceutil/trace.go:171","msg":"trace[1773196242] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"158.358666ms","start":"2026-04-08T23:04:21.322888Z","end":"2026-04-08T23:04:21.481246Z","steps":["trace[1773196242] 'process raft request' (duration: 158.097552ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:04:21.566249Z","caller":"traceutil/trace.go:171","msg":"trace[67432057] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"164.89714ms","start":"2026-04-08T23:04:21.401316Z","end":"2026-04-08T23:04:21.566213Z","steps":["trace[67432057] 'process raft request' (duration: 88.943056ms)","trace[67432057] 'compare' (duration: 75.699649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:04:21.861893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.159611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T23:04:21.862092Z","caller":"traceutil/trace.go:171","msg":"trace[1955097727] linearizableReadLoop","detail":"{readStateIndex:5278; appliedIndex:5275; }","duration":"118.694114ms","start":"2026-04-08T23:04:21.743381Z","end":"2026-04-08T23:04:21.862075Z","steps":["trace[1955097727] 'read index received' (duration: 6.252448ms)","trace[1955097727] 'applied index is now lower than readState.Index' (duration: 112.440686ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:04:21.862131Z","caller":"traceutil/trace.go:171","msg":"trace[2116889696] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"290.568653ms","start":"2026-04-08T23:04:21.571555Z","end":"2026-04-08T23:04:21.862124Z","steps":["trace[2116889696] 'process raft request' (duration: 178.086256ms)","trace[2116889696] 'compare' (duration: 112.027049ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:04:21.862246Z","caller":"traceutil/trace.go:171","msg":"trace[1276218420] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"121.739121ms","start":"2026-04-08T23:04:21.7405Z","end":"2026-04-08T23:04:21.862239Z","steps":["trace[1276218420] 'process raft request' (duration: 121.540967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:04:21.862373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.997699ms","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-04-08T23:04:21.862403Z","caller":"traceutil/trace.go:171","msg":"trace[801084505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5083; }","duration":"119.066661ms","start":"2026-04-08T23:04:21.743329Z","end":"2026-04-08T23:04:21.862395Z","steps":["trace[801084505] 'agreement among raft nodes before linearized reading' (duration: 118.975099ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:04:21.8621Z","caller":"traceutil/trace.go:171","msg":"trace[119710601] transaction","detail":"{read_only:false; response_revision:5082; number_of_response:1; }","duration":"290.41516ms","start":"2026-04-08T23:04:21.571673Z","end":"2026-04-08T23:04:21.862089Z","steps":["trace[119710601] 'process raft request' (duration: 290.315978ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:04:30.006487Z","caller":"traceutil/trace.go:171","msg":"trace[1935734336] linearizableReadLoop","detail":"{readStateIndex:5449; appliedIndex:5448; }","duration":"104.871135ms","start":"2026-04-08T23:04:29.901587Z","end":"2026-04-08T23:04:30.006458Z","steps":["trace[1935734336] 'read index received' (duration: 104.707312ms)","trace[1935734336] 'applied index is now lower than readState.Index' (duration: 162.773µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:04:30.006638Z","caller":"traceutil/trace.go:171","msg":"trace[2023011324] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"117.787306ms","start":"2026-04-08T23:04:29.888836Z","end":"2026-04-08T23:04:30.006623Z","steps":["trace[2023011324] 'process raft request' (duration: 117.43886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:04:30.00701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.066834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T23:04:30.007018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.423024ms","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-08T23:04:30.007101Z","caller":"traceutil/trace.go:171","msg":"trace[339495377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5249; }","duration":"100.189886ms","start":"2026-04-08T23:04:29.906893Z","end":"2026-04-08T23:04:30.007083Z","steps":["trace[339495377] 'agreement among raft nodes before linearized reading' (duration: 99.974212ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:04:30.007108Z","caller":"traceutil/trace.go:171","msg":"trace[184896306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5249; }","duration":"105.578897ms","start":"2026-04-08T23:04:29.901511Z","end":"2026-04-08T23:04:30.00709Z","steps":["trace[184896306] 'agreement among raft nodes before linearized reading' (duration: 105.366203ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:04:30.232677Z","caller":"traceutil/trace.go:171","msg":"trace[1997849908] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"220.725965ms","start":"2026-04-08T23:04:30.011914Z","end":"2026-04-08T23:04:30.23264Z","steps":["trace[1997849908] 'process raft request' (duration: 166.729244ms)","trace[1997849908] 'compare' (duration: 53.848738ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:04:30.244765Z","caller":"traceutil/trace.go:171","msg":"trace[745405475] linearizableReadLoop","detail":"{readStateIndex:5451; appliedIndex:5449; }","duration":"119.300905ms","start":"2026-04-08T23:04:30.12544Z","end":"2026-04-08T23:04:30.244741Z","steps":["trace[745405475] 'read index received' (duration: 53.214937ms)","trace[745405475] 'applied index is now lower than readState.Index' (duration: 66.084508ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:04:30.244888Z","caller":"traceutil/trace.go:171","msg":"trace[1599013792] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"232.74109ms","start":"2026-04-08T23:04:30.012135Z","end":"2026-04-08T23:04:30.244876Z","steps":["trace[1599013792] 'process raft request' (duration: 232.415854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:04:30.245056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.835976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-2z4f9\" ","response":"range_response_count:1 size:4040"} {"level":"info","ts":"2026-04-08T23:04:30.245119Z","caller":"traceutil/trace.go:171","msg":"trace[1967704531] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-2z4f9; range_end:; response_count:1; response_revision:5251; }","duration":"110.955438ms","start":"2026-04-08T23:04:30.134147Z","end":"2026-04-08T23:04:30.245103Z","steps":["trace[1967704531] 'agreement among raft nodes before linearized reading' (duration: 110.765145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:04:30.245462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.000398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zw6fd\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T23:04:30.245566Z","caller":"traceutil/trace.go:171","msg":"trace[1826114870] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zw6fd; range_end:; response_count:1; response_revision:5251; }","duration":"120.184901ms","start":"2026-04-08T23:04:30.125361Z","end":"2026-04-08T23:04:30.245546Z","steps":["trace[1826114870] 'agreement among raft nodes before linearized reading' (duration: 119.838894ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:04:30.359211Z","caller":"traceutil/trace.go:171","msg":"trace[995221212] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"107.356682ms","start":"2026-04-08T23:04:30.251823Z","end":"2026-04-08T23:04:30.35918Z","steps":["trace[995221212] 'process raft request' (duration: 90.998965ms)","trace[995221212] 'compare' (duration: 16.194054ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:06:15.776172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3012} {"level":"info","ts":"2026-04-08T23:06:15.846502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3012,"took":"69.220315ms","hash":95052360,"current-db-size-bytes":59170816,"current-db-size":"59 MB","current-db-size-in-use-bytes":32272384,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-08T23:06:15.846575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":95052360,"revision":3012,"compact-revision":-1} {"level":"info","ts":"2026-04-08T23:10:21.389458Z","caller":"traceutil/trace.go:171","msg":"trace[1844983064] linearizableReadLoop","detail":"{readStateIndex:8287; appliedIndex:8286; }","duration":"101.450833ms","start":"2026-04-08T23:10:21.287982Z","end":"2026-04-08T23:10:21.389433Z","steps":["trace[1844983064] 'read index received' (duration: 83.421017ms)","trace[1844983064] 'applied index is now lower than readState.Index' (duration: 18.028856ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:10:21.389616Z","caller":"traceutil/trace.go:171","msg":"trace[1808502024] transaction","detail":"{read_only:false; response_revision:8003; number_of_response:1; }","duration":"226.273453ms","start":"2026-04-08T23:10:21.163314Z","end":"2026-04-08T23:10:21.389588Z","steps":["trace[1808502024] 'process raft request' (duration: 208.081844ms)","trace[1808502024] 'compare' (duration: 17.756991ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:10:21.389758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.747889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T23:10:21.389927Z","caller":"traceutil/trace.go:171","msg":"trace[1401865132] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8003; }","duration":"101.963383ms","start":"2026-04-08T23:10:21.287914Z","end":"2026-04-08T23:10:21.389878Z","steps":["trace[1401865132] 'agreement among raft nodes before linearized reading' (duration: 101.673618ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:10:21.40794Z","caller":"traceutil/trace.go:171","msg":"trace[1154808192] transaction","detail":"{read_only:false; response_revision:8004; number_of_response:1; }","duration":"108.379092ms","start":"2026-04-08T23:10:21.299519Z","end":"2026-04-08T23:10:21.407898Z","steps":["trace[1154808192] 'process raft request' (duration: 108.087937ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:10:33.051991Z","caller":"traceutil/trace.go:171","msg":"trace[954839843] transaction","detail":"{read_only:false; response_revision:8065; number_of_response:1; }","duration":"104.088034ms","start":"2026-04-08T23:10:32.947871Z","end":"2026-04-08T23:10:33.051959Z","steps":["trace[954839843] 'process raft request' (duration: 103.881369ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:02.842352Z","caller":"traceutil/trace.go:171","msg":"trace[1761688611] linearizableReadLoop","detail":"{readStateIndex:8576; appliedIndex:8575; }","duration":"127.930558ms","start":"2026-04-08T23:11:02.714393Z","end":"2026-04-08T23:11:02.842324Z","steps":["trace[1761688611] 'read index received' (duration: 127.687023ms)","trace[1761688611] 'applied index is now lower than readState.Index' (duration: 242.425µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:11:02.842444Z","caller":"traceutil/trace.go:171","msg":"trace[799032387] transaction","detail":"{read_only:false; response_revision:8281; number_of_response:1; }","duration":"147.589984ms","start":"2026-04-08T23:11:02.694802Z","end":"2026-04-08T23:11:02.842392Z","steps":["trace[799032387] 'process raft request' (duration: 147.34621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:02.842522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.102571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T23:11:02.842567Z","caller":"traceutil/trace.go:171","msg":"trace[1953069823] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8281; }","duration":"128.202932ms","start":"2026-04-08T23:11:02.714352Z","end":"2026-04-08T23:11:02.842555Z","steps":["trace[1953069823] 'agreement among raft nodes before linearized reading' (duration: 128.09149ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:15.566573Z","caller":"traceutil/trace.go:171","msg":"trace[558511528] transaction","detail":"{read_only:false; response_revision:8386; number_of_response:1; }","duration":"255.781893ms","start":"2026-04-08T23:11:15.310756Z","end":"2026-04-08T23:11:15.566538Z","steps":["trace[558511528] 'process raft request' (duration: 218.422226ms)","trace[558511528] 'compare' (duration: 37.230194ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:11:15.569928Z","caller":"traceutil/trace.go:171","msg":"trace[1271552715] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8387; }","duration":"258.566676ms","start":"2026-04-08T23:11:15.311331Z","end":"2026-04-08T23:11:15.569898Z","steps":["trace[1271552715] 'process raft request' (duration: 257.877653ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:15.707535Z","caller":"traceutil/trace.go:171","msg":"trace[347806712] transaction","detail":"{read_only:false; response_revision:8388; number_of_response:1; }","duration":"115.776202ms","start":"2026-04-08T23:11:15.591708Z","end":"2026-04-08T23:11:15.707484Z","steps":["trace[347806712] 'process raft request' (duration: 115.340344ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:15.897543Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6174} {"level":"info","ts":"2026-04-08T23:11:15.897595Z","caller":"traceutil/trace.go:171","msg":"trace[1015925988] compact","detail":"{revision:6174; response_revision:8390; }","duration":"109.075236ms","start":"2026-04-08T23:11:15.788482Z","end":"2026-04-08T23:11:15.897557Z","steps":["trace[1015925988] 'process raft request' (duration: 64.364642ms)","trace[1015925988] 'check and update compact revision' (duration: 44.55604ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:11:16.439913Z","caller":"traceutil/trace.go:171","msg":"trace[211179039] linearizableReadLoop","detail":"{readStateIndex:8692; appliedIndex:8691; }","duration":"194.832926ms","start":"2026-04-08T23:11:16.245054Z","end":"2026-04-08T23:11:16.439887Z","steps":["trace[211179039] 'read index received' (duration: 111.635874ms)","trace[211179039] 'applied index is now lower than readState.Index' (duration: 83.193322ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:11:16.439982Z","caller":"traceutil/trace.go:171","msg":"trace[538250488] transaction","detail":"{read_only:false; response_revision:8391; number_of_response:1; }","duration":"270.409767ms","start":"2026-04-08T23:11:16.169524Z","end":"2026-04-08T23:11:16.439934Z","steps":["trace[538250488] 'process raft request' (duration: 187.195084ms)","trace[538250488] 'compare' (duration: 60.208384ms)","trace[538250488] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; req_size:531; } (duration: 22.828746ms)"],"step_count":3} {"level":"warn","ts":"2026-04-08T23:11:16.440204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.133201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-08T23:11:16.440285Z","caller":"traceutil/trace.go:171","msg":"trace[1796151931] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8391; }","duration":"195.274714ms","start":"2026-04-08T23:11:16.244992Z","end":"2026-04-08T23:11:16.440267Z","steps":["trace[1796151931] 'agreement among raft nodes before linearized reading' (duration: 195.022149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:16.443355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.616203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-08T23:11:16.443389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.074487ms","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-08T23:11:16.443411Z","caller":"traceutil/trace.go:171","msg":"trace[400210254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8392; }","duration":"118.699774ms","start":"2026-04-08T23:11:16.324698Z","end":"2026-04-08T23:11:16.443397Z","steps":["trace[400210254] 'agreement among raft nodes before linearized reading' (duration: 118.577872ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:16.443446Z","caller":"traceutil/trace.go:171","msg":"trace[377723270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8392; }","duration":"107.155429ms","start":"2026-04-08T23:11:16.336274Z","end":"2026-04-08T23:11:16.44343Z","steps":["trace[377723270] 'agreement among raft nodes before linearized reading' (duration: 106.993126ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:16.443428Z","caller":"traceutil/trace.go:171","msg":"trace[51188228] transaction","detail":"{read_only:false; response_revision:8392; number_of_response:1; }","duration":"150.883454ms","start":"2026-04-08T23:11:16.292494Z","end":"2026-04-08T23:11:16.443378Z","steps":["trace[51188228] 'process raft request' (duration: 150.534898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:16.443524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.332752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414394"} {"level":"warn","ts":"2026-04-08T23:11:16.443261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.265634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T23:11:16.443651Z","caller":"traceutil/trace.go:171","msg":"trace[729756121] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8392; }","duration":"180.687362ms","start":"2026-04-08T23:11:16.262935Z","end":"2026-04-08T23:11:16.443623Z","steps":["trace[729756121] 'agreement among raft nodes before linearized reading' (duration: 180.236083ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:16.443583Z","caller":"traceutil/trace.go:171","msg":"trace[905192439] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8392; }","duration":"116.466814ms","start":"2026-04-08T23:11:16.327103Z","end":"2026-04-08T23:11:16.44357Z","steps":["trace[905192439] 'agreement among raft nodes before linearized reading' (duration: 116.123997ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:16.486612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6174,"took":"586.158718ms","hash":1523765119,"current-db-size-bytes":59170816,"current-db-size":"59 MB","current-db-size-in-use-bytes":23228416,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-08T23:11:16.486728Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1523765119,"revision":6174,"compact-revision":3012} {"level":"info","ts":"2026-04-08T23:11:17.033217Z","caller":"traceutil/trace.go:171","msg":"trace[557396720] linearizableReadLoop","detail":"{readStateIndex:8703; appliedIndex:8702; }","duration":"181.196092ms","start":"2026-04-08T23:11:16.851994Z","end":"2026-04-08T23:11:17.03319Z","steps":["trace[557396720] 'read index received' (duration: 180.699022ms)","trace[557396720] 'applied index is now lower than readState.Index' (duration: 495.95µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:11:17.033443Z","caller":"traceutil/trace.go:171","msg":"trace[553567760] transaction","detail":"{read_only:false; response_revision:8402; number_of_response:1; }","duration":"205.405994ms","start":"2026-04-08T23:11:16.828009Z","end":"2026-04-08T23:11:17.033415Z","steps":["trace[553567760] 'process raft request' (duration: 204.802842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:17.033552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.532468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-04-08T23:11:17.033619Z","caller":"traceutil/trace.go:171","msg":"trace[2138438304] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8402; }","duration":"181.64023ms","start":"2026-04-08T23:11:16.851959Z","end":"2026-04-08T23:11:17.0336Z","steps":["trace[2138438304] 'agreement among raft nodes before linearized reading' (duration: 181.420956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T23:11:17.033689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.12623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T23:11:17.033843Z","caller":"traceutil/trace.go:171","msg":"trace[956184210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8402; }","duration":"114.357664ms","start":"2026-04-08T23:11:16.919468Z","end":"2026-04-08T23:11:17.033825Z","steps":["trace[956184210] 'agreement among raft nodes before linearized reading' (duration: 113.985558ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:17.1504Z","caller":"traceutil/trace.go:171","msg":"trace[1704928746] linearizableReadLoop","detail":"{readStateIndex:8704; appliedIndex:8703; }","duration":"106.570079ms","start":"2026-04-08T23:11:17.04381Z","end":"2026-04-08T23:11:17.15038Z","steps":["trace[1704928746] 'read index received' (duration: 31.383317ms)","trace[1704928746] 'applied index is now lower than readState.Index' (duration: 75.185902ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T23:11:17.15058Z","caller":"traceutil/trace.go:171","msg":"trace[1298709406] transaction","detail":"{read_only:false; response_revision:8403; number_of_response:1; }","duration":"110.278998ms","start":"2026-04-08T23:11:17.040292Z","end":"2026-04-08T23:11:17.150571Z","steps":["trace[1298709406] 'process raft request' (duration: 34.962323ms)","trace[1298709406] 'compare' (duration: 74.878627ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T23:11:17.150791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.941407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-08T23:11:17.150816Z","caller":"traceutil/trace.go:171","msg":"trace[1267266504] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:8403; }","duration":"107.014898ms","start":"2026-04-08T23:11:17.043794Z","end":"2026-04-08T23:11:17.150809Z","steps":["trace[1267266504] 'agreement among raft nodes before linearized reading' (duration: 106.894856ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T23:11:58.993226Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000238e.wal"} {"level":"info","ts":"2026-04-08T23:13:45.245982Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T23:13:45.259587Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T23:13:45.259904Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T23:16:15.915918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8390} {"level":"info","ts":"2026-04-08T23:16:16.007299Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8390,"took":"89.121024ms","hash":571516176,"current-db-size-bytes":59170816,"current-db-size":"59 MB","current-db-size-in-use-bytes":24289280,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-08T23:16:16.007394Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":571516176,"revision":8390,"compact-revision":6174} {"level":"info","ts":"2026-04-08T23:21:15.922526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10688} {"level":"info","ts":"2026-04-08T23:21:16.017417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10688,"took":"91.973948ms","hash":2320045784,"current-db-size-bytes":59170816,"current-db-size":"59 MB","current-db-size-in-use-bytes":20025344,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-08T23:21:16.017505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2320045784,"revision":10688,"compact-revision":8390}