{"level":"warn","ts":"2026-05-09T21:41:05.840478Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T21:41:05.840606Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T21:41:05.840693Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T21:41:05.840711Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-05-09T21:41:05.840732Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T21:41:05.841735Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-05-09T21:41:05.84183Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T21:41:05.845849Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.727582ms"} {"level":"info","ts":"2026-05-09T21:41:05.851323Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-05-09T21:41:05.851389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T21:41:05.851406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-05-09T21:41:05.851423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T21:41:05.851436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-05-09T21:41:05.851465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-05-09T21:41:05.860043Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T21:41:05.863299Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T21:41:05.864759Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-09T21:41:05.866936Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T21:41:05.867317Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T21:41:05.867428Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T21:41:05.867503Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T21:41:05.867517Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T21:41:05.869868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-05-09T21:41:05.872801Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-05-09T21:41:05.874183Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T21:41:05.874437Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-05-09T21:41:05.874505Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T21:41:05.874556Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T21:41:05.874472Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-05-09T21:41:06.052379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T21:41:06.052479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T21:41:06.05251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-05-09T21:41:06.05253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-05-09T21:41:06.052684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-05-09T21:41:06.0527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-05-09T21:41:06.052714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-05-09T21:41:06.053554Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T21:41:06.053728Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:41:06.053846Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:41:06.054058Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:41:06.054227Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T21:41:06.054348Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T21:41:06.055158Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:41:06.055431Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:41:06.055619Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:41:06.056609Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-05-09T21:41:06.057074Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T21:41:08.556332Z","caller":"traceutil/trace.go:171","msg":"trace[1735967709] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"104.394826ms","start":"2026-05-09T21:41:08.451893Z","end":"2026-05-09T21:41:08.556288Z","steps":["trace[1735967709] 'read index received' (duration: 104.239971ms)","trace[1735967709] 'applied index is now lower than readState.Index' (duration: 154.045µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:41:08.556592Z","caller":"traceutil/trace.go:171","msg":"trace[1463174029] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"104.610932ms","start":"2026-05-09T21:41:08.451763Z","end":"2026-05-09T21:41:08.556374Z","steps":["trace[1463174029] 'process raft request' (duration: 104.414337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:41:08.556811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.574203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-09T21:41:08.556803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.88966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T21:41:08.557008Z","caller":"traceutil/trace.go:171","msg":"trace[1677262249] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:73; }","duration":"105.130585ms","start":"2026-05-09T21:41:08.451859Z","end":"2026-05-09T21:41:08.556989Z","steps":["trace[1677262249] 'agreement among raft nodes before linearized reading' (duration: 104.748995ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:08.55719Z","caller":"traceutil/trace.go:171","msg":"trace[466417540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"100.754958ms","start":"2026-05-09T21:41:08.456169Z","end":"2026-05-09T21:41:08.556924Z","steps":["trace[466417540] 'agreement among raft nodes before linearized reading' (duration: 100.45408ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:08.680759Z","caller":"traceutil/trace.go:171","msg":"trace[368899585] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"116.445629ms","start":"2026-05-09T21:41:08.564285Z","end":"2026-05-09T21:41:08.680731Z","steps":["trace[368899585] 'read index received' (duration: 103.389658ms)","trace[368899585] 'applied index is now lower than readState.Index' (duration: 13.055001ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:41:08.680925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.619985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-09T21:41:08.680983Z","caller":"traceutil/trace.go:171","msg":"trace[773923840] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:74; }","duration":"116.708846ms","start":"2026-05-09T21:41:08.56425Z","end":"2026-05-09T21:41:08.680959Z","steps":["trace[773923840] 'agreement among raft nodes before linearized reading' (duration: 116.573503ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:08.68102Z","caller":"traceutil/trace.go:171","msg":"trace[511784050] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"118.357191ms","start":"2026-05-09T21:41:08.562616Z","end":"2026-05-09T21:41:08.680973Z","steps":["trace[511784050] 'process raft request' (duration: 105.058963ms)","trace[511784050] 'compare' (duration: 12.930248ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:41:08.801373Z","caller":"traceutil/trace.go:171","msg":"trace[783751369] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"111.427115ms","start":"2026-05-09T21:41:08.689918Z","end":"2026-05-09T21:41:08.801345Z","steps":["trace[783751369] 'process raft request' (duration: 108.466855ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:35.127836Z","caller":"traceutil/trace.go:171","msg":"trace[220667234] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"178.30602ms","start":"2026-05-09T21:41:34.949493Z","end":"2026-05-09T21:41:35.127799Z","steps":["trace[220667234] 'process raft request' (duration: 178.184147ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:35.134531Z","caller":"traceutil/trace.go:171","msg":"trace[1117670848] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"116.599698ms","start":"2026-05-09T21:41:35.017911Z","end":"2026-05-09T21:41:35.134511Z","steps":["trace[1117670848] 'process raft request' (duration: 116.561807ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:35.134762Z","caller":"traceutil/trace.go:171","msg":"trace[1778255132] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"181.659569ms","start":"2026-05-09T21:41:34.953095Z","end":"2026-05-09T21:41:35.134755Z","steps":["trace[1778255132] 'process raft request' (duration: 181.018114ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:35.134844Z","caller":"traceutil/trace.go:171","msg":"trace[876011306] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"181.686119ms","start":"2026-05-09T21:41:34.953152Z","end":"2026-05-09T21:41:35.134838Z","steps":["trace[876011306] 'process raft request' (duration: 181.048874ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:35.134915Z","caller":"traceutil/trace.go:171","msg":"trace[1562772938] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"174.397107ms","start":"2026-05-09T21:41:34.960512Z","end":"2026-05-09T21:41:35.13491Z","steps":["trace[1562772938] 'process raft request' (duration: 173.713521ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:35.134986Z","caller":"traceutil/trace.go:171","msg":"trace[933449465] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"165.557237ms","start":"2026-05-09T21:41:34.969424Z","end":"2026-05-09T21:41:35.134981Z","steps":["trace[933449465] 'process raft request' (duration: 164.82834ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:35.13507Z","caller":"traceutil/trace.go:171","msg":"trace[2055311932] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"157.617389ms","start":"2026-05-09T21:41:34.977446Z","end":"2026-05-09T21:41:35.135064Z","steps":["trace[2055311932] 'process raft request' (duration: 156.83148ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:35.135139Z","caller":"traceutil/trace.go:171","msg":"trace[1395663288] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"155.085178ms","start":"2026-05-09T21:41:34.980048Z","end":"2026-05-09T21:41:35.135133Z","steps":["trace[1395663288] 'process raft request' (duration: 154.262829ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:35.135207Z","caller":"traceutil/trace.go:171","msg":"trace[1966013988] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"148.457421ms","start":"2026-05-09T21:41:34.986745Z","end":"2026-05-09T21:41:35.135202Z","steps":["trace[1966013988] 'process raft request' (duration: 147.58797ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:35.13529Z","caller":"traceutil/trace.go:171","msg":"trace[68756188] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"148.460162ms","start":"2026-05-09T21:41:34.986824Z","end":"2026-05-09T21:41:35.135284Z","steps":["trace[68756188] 'process raft request' (duration: 147.53426ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:35.135361Z","caller":"traceutil/trace.go:171","msg":"trace[260957696] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"143.620317ms","start":"2026-05-09T21:41:34.991736Z","end":"2026-05-09T21:41:35.135356Z","steps":["trace[260957696] 'process raft request' (duration: 142.666134ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:39.574171Z","caller":"traceutil/trace.go:171","msg":"trace[1940740285] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"107.8744ms","start":"2026-05-09T21:41:39.466276Z","end":"2026-05-09T21:41:39.574151Z","steps":["trace[1940740285] 'process raft request' (duration: 105.831083ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:41.087025Z","caller":"traceutil/trace.go:171","msg":"trace[602225014] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"110.372276ms","start":"2026-05-09T21:41:40.976614Z","end":"2026-05-09T21:41:41.086986Z","steps":["trace[602225014] 'process raft request' (duration: 108.37193ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:41.248625Z","caller":"traceutil/trace.go:171","msg":"trace[1162410533] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"104.16402ms","start":"2026-05-09T21:41:41.144429Z","end":"2026-05-09T21:41:41.248593Z","steps":["trace[1162410533] 'process raft request' (duration: 39.531998ms)","trace[1162410533] 'compare' (duration: 64.495939ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:41:41.795847Z","caller":"traceutil/trace.go:171","msg":"trace[227435010] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"214.889602ms","start":"2026-05-09T21:41:41.580926Z","end":"2026-05-09T21:41:41.795816Z","steps":["trace[227435010] 'process raft request' (duration: 214.649937ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:42.040909Z","caller":"traceutil/trace.go:171","msg":"trace[2143840016] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"389.141189ms","start":"2026-05-09T21:41:41.651738Z","end":"2026-05-09T21:41:42.04088Z","steps":["trace[2143840016] 'process raft request' (duration: 388.30227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:41:42.041324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:41:41.651718Z","time spent":"389.249021ms","remote":"127.0.0.1:54096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:41:42.041461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.470509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T21:41:42.041491Z","caller":"traceutil/trace.go:171","msg":"trace[795671838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"294.544381ms","start":"2026-05-09T21:41:41.74694Z","end":"2026-05-09T21:41:42.041485Z","steps":["trace[795671838] 'agreement among raft nodes before linearized reading' (duration: 294.429888ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:42.041567Z","caller":"traceutil/trace.go:171","msg":"trace[1030505611] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"380.707113ms","start":"2026-05-09T21:41:41.660848Z","end":"2026-05-09T21:41:42.041555Z","steps":["trace[1030505611] 'process raft request' (duration: 380.082038ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:41:42.041333Z","caller":"traceutil/trace.go:171","msg":"trace[540759073] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:636; }","duration":"294.334156ms","start":"2026-05-09T21:41:41.746982Z","end":"2026-05-09T21:41:42.041316Z","steps":["trace[540759073] 'read index received' (duration: 48.758623ms)","trace[540759073] 'applied index is now lower than readState.Index' (duration: 245.575053ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:41:42.041618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:41:41.660819Z","time spent":"380.773645ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:41:42.041668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.940285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T21:41:42.041688Z","caller":"traceutil/trace.go:171","msg":"trace[850917864] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"181.998326ms","start":"2026-05-09T21:41:41.859685Z","end":"2026-05-09T21:41:42.041684Z","steps":["trace[850917864] 'agreement among raft nodes before linearized reading' (duration: 181.948485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:41:57.476627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.437156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-09T21:41:57.476708Z","caller":"traceutil/trace.go:171","msg":"trace[548408887] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"231.531158ms","start":"2026-05-09T21:41:57.245161Z","end":"2026-05-09T21:41:57.476692Z","steps":["trace[548408887] 'range keys from in-memory index tree' (duration: 231.231611ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:42:26.554108Z","caller":"traceutil/trace.go:171","msg":"trace[999200062] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1220; }","duration":"216.700271ms","start":"2026-05-09T21:42:26.337385Z","end":"2026-05-09T21:42:26.554085Z","steps":["trace[999200062] 'read index received' (duration: 216.513027ms)","trace[999200062] 'applied index is now lower than readState.Index' (duration: 186.404µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:42:26.554365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.965196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T21:42:26.554406Z","caller":"traceutil/trace.go:171","msg":"trace[249032576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1186; }","duration":"217.064138ms","start":"2026-05-09T21:42:26.337332Z","end":"2026-05-09T21:42:26.554396Z","steps":["trace[249032576] 'agreement among raft nodes before linearized reading' (duration: 216.849694ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:42:26.554204Z","caller":"traceutil/trace.go:171","msg":"trace[702108511] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"249.998015ms","start":"2026-05-09T21:42:26.304175Z","end":"2026-05-09T21:42:26.554173Z","steps":["trace[702108511] 'process raft request' (duration: 249.78276ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:42:47.024743Z","caller":"traceutil/trace.go:171","msg":"trace[1390292067] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"124.109042ms","start":"2026-05-09T21:42:46.900608Z","end":"2026-05-09T21:42:47.024717Z","steps":["trace[1390292067] 'process raft request' (duration: 123.933406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:43:09.490786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.843868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:523"} {"level":"info","ts":"2026-05-09T21:43:09.490868Z","caller":"traceutil/trace.go:171","msg":"trace[1686287989] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1823; }","duration":"182.960791ms","start":"2026-05-09T21:43:09.307889Z","end":"2026-05-09T21:43:09.49085Z","steps":["trace[1686287989] 'range keys from in-memory index tree' (duration: 182.614911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:43:09.491073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.229175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:43:09.491105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.376807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-05-09T21:43:09.491165Z","caller":"traceutil/trace.go:171","msg":"trace[1772888037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1823; }","duration":"202.73661ms","start":"2026-05-09T21:43:09.28841Z","end":"2026-05-09T21:43:09.491147Z","steps":["trace[1772888037] 'range keys from in-memory index tree' (duration: 202.056631ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:43:09.491183Z","caller":"traceutil/trace.go:171","msg":"trace[2100748412] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1823; }","duration":"180.49626ms","start":"2026-05-09T21:43:09.310667Z","end":"2026-05-09T21:43:09.491164Z","steps":["trace[2100748412] 'range keys from in-memory index tree' (duration: 180.174772ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:43:09.709407Z","caller":"traceutil/trace.go:171","msg":"trace[792119788] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"123.197613ms","start":"2026-05-09T21:43:09.586173Z","end":"2026-05-09T21:43:09.70937Z","steps":["trace[792119788] 'process raft request' (duration: 123.027708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:43:09.709498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.295556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-09T21:43:09.709544Z","caller":"traceutil/trace.go:171","msg":"trace[1000600933] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1827; }","duration":"123.375857ms","start":"2026-05-09T21:43:09.586158Z","end":"2026-05-09T21:43:09.709534Z","steps":["trace[1000600933] 'agreement among raft nodes before linearized reading' (duration: 123.279355ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:43:09.709346Z","caller":"traceutil/trace.go:171","msg":"trace[1758474725] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1880; }","duration":"123.13379ms","start":"2026-05-09T21:43:09.58619Z","end":"2026-05-09T21:43:09.709324Z","steps":["trace[1758474725] 'read index received' (duration: 122.930845ms)","trace[1758474725] 'applied index is now lower than readState.Index' (duration: 202.005µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:43:52.843035Z","caller":"traceutil/trace.go:171","msg":"trace[1519841746] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"127.300242ms","start":"2026-05-09T21:43:52.715711Z","end":"2026-05-09T21:43:52.843011Z","steps":["trace[1519841746] 'process raft request' (duration: 127.184319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:44:33.813468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.752346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-09T21:44:33.813569Z","caller":"traceutil/trace.go:171","msg":"trace[1341452927] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2553; }","duration":"178.89218ms","start":"2026-05-09T21:44:33.634651Z","end":"2026-05-09T21:44:33.813543Z","steps":["trace[1341452927] 'range keys from in-memory index tree' (duration: 178.568412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:44:33.813497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.981485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-05-09T21:44:33.8137Z","caller":"traceutil/trace.go:171","msg":"trace[1510885844] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2553; }","duration":"106.223171ms","start":"2026-05-09T21:44:33.707443Z","end":"2026-05-09T21:44:33.813667Z","steps":["trace[1510885844] 'range keys from in-memory index tree' (duration: 105.735249ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:02.215561Z","caller":"traceutil/trace.go:171","msg":"trace[660260683] linearizableReadLoop","detail":"{readStateIndex:2855; appliedIndex:2854; }","duration":"142.531024ms","start":"2026-05-09T21:45:02.073004Z","end":"2026-05-09T21:45:02.215535Z","steps":["trace[660260683] 'read index received' (duration: 60.369485ms)","trace[660260683] 'applied index is now lower than readState.Index' (duration: 82.160479ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:45:02.215616Z","caller":"traceutil/trace.go:171","msg":"trace[2114562548] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"189.237546ms","start":"2026-05-09T21:45:02.026351Z","end":"2026-05-09T21:45:02.215589Z","steps":["trace[2114562548] 'process raft request' (duration: 106.974345ms)","trace[2114562548] 'compare' (duration: 82.083877ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:45:02.215929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.048488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:45:02.216043Z","caller":"traceutil/trace.go:171","msg":"trace[543836279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2751; }","duration":"130.209102ms","start":"2026-05-09T21:45:02.085812Z","end":"2026-05-09T21:45:02.216022Z","steps":["trace[543836279] 'agreement among raft nodes before linearized reading' (duration: 129.931556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:02.216049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.028335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-09T21:45:02.216254Z","caller":"traceutil/trace.go:171","msg":"trace[1096453547] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2751; }","duration":"143.116297ms","start":"2026-05-09T21:45:02.072966Z","end":"2026-05-09T21:45:02.216082Z","steps":["trace[1096453547] 'agreement among raft nodes before linearized reading' (duration: 142.704877ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:23.843642Z","caller":"traceutil/trace.go:171","msg":"trace[1732473007] transaction","detail":"{read_only:false; response_revision:4457; number_of_response:1; }","duration":"116.46445ms","start":"2026-05-09T21:47:23.727148Z","end":"2026-05-09T21:47:23.843612Z","steps":["trace[1732473007] 'process raft request' (duration: 116.228114ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:24.127049Z","caller":"traceutil/trace.go:171","msg":"trace[2057180042] linearizableReadLoop","detail":"{readStateIndex:4653; appliedIndex:4652; }","duration":"146.675373ms","start":"2026-05-09T21:47:23.98035Z","end":"2026-05-09T21:47:24.127026Z","steps":["trace[2057180042] 'read index received' (duration: 60.776932ms)","trace[2057180042] 'applied index is now lower than readState.Index' (duration: 85.897461ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:24.127175Z","caller":"traceutil/trace.go:171","msg":"trace[1210581708] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4461; }","duration":"203.360946ms","start":"2026-05-09T21:47:23.923803Z","end":"2026-05-09T21:47:24.127164Z","steps":["trace[1210581708] 'process raft request' (duration: 117.390333ms)","trace[1210581708] 'compare' (duration: 85.798569ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:24.127415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.051463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:24.127457Z","caller":"traceutil/trace.go:171","msg":"trace[1991871947] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:4461; }","duration":"147.165206ms","start":"2026-05-09T21:47:23.980282Z","end":"2026-05-09T21:47:24.127447Z","steps":["trace[1991871947] 'agreement among raft nodes before linearized reading' (duration: 147.080394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:24.127662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.555125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T21:47:24.127731Z","caller":"traceutil/trace.go:171","msg":"trace[1673453545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4461; }","duration":"129.624837ms","start":"2026-05-09T21:47:23.998092Z","end":"2026-05-09T21:47:24.127717Z","steps":["trace[1673453545] 'agreement among raft nodes before linearized reading' (duration: 129.445202ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:24.328453Z","caller":"traceutil/trace.go:171","msg":"trace[1972888757] transaction","detail":"{read_only:false; response_revision:4465; number_of_response:1; }","duration":"105.808103ms","start":"2026-05-09T21:47:24.222619Z","end":"2026-05-09T21:47:24.328427Z","steps":["trace[1972888757] 'process raft request' (duration: 17.716743ms)","trace[1972888757] 'compare' (duration: 87.827653ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:24.329464Z","caller":"traceutil/trace.go:171","msg":"trace[138475363] transaction","detail":"{read_only:false; response_revision:4466; number_of_response:1; }","duration":"105.112664ms","start":"2026-05-09T21:47:24.224311Z","end":"2026-05-09T21:47:24.329423Z","steps":["trace[138475363] 'process raft request' (duration: 104.068056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:31.239477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.161148ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:47:31.239564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.887134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-09T21:47:31.239626Z","caller":"traceutil/trace.go:171","msg":"trace[1727288502] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4594; }","duration":"797.980437ms","start":"2026-05-09T21:47:30.44163Z","end":"2026-05-09T21:47:31.23961Z","steps":["trace[1727288502] 'range keys from in-memory index tree' (duration: 797.567625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:31.239614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.966845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-09T21:47:31.239622Z","caller":"traceutil/trace.go:171","msg":"trace[75907759] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4594; }","duration":"504.325853ms","start":"2026-05-09T21:47:30.735271Z","end":"2026-05-09T21:47:31.239597Z","steps":["trace[75907759] 'range keys from in-memory index tree' (duration: 504.141788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:31.239652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:30.441617Z","time spent":"798.028328ms","remote":"127.0.0.1:53934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-09T21:47:31.239728Z","caller":"traceutil/trace.go:171","msg":"trace[142371127] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4594; }","duration":"532.122799ms","start":"2026-05-09T21:47:30.70758Z","end":"2026-05-09T21:47:31.239703Z","steps":["trace[142371127] 'range keys from in-memory index tree' (duration: 531.686887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:31.240246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:30.707559Z","time spent":"532.670153ms","remote":"127.0.0.1:54096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-05-09T21:47:36.889685Z","caller":"traceutil/trace.go:171","msg":"trace[721571314] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"246.707711ms","start":"2026-05-09T21:47:36.642944Z","end":"2026-05-09T21:47:36.889651Z","steps":["trace[721571314] 'process raft request' (duration: 246.542797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:37.312634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.261653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:37.312721Z","caller":"traceutil/trace.go:171","msg":"trace[1252985566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4650; }","duration":"227.391697ms","start":"2026-05-09T21:47:37.085311Z","end":"2026-05-09T21:47:37.312703Z","steps":["trace[1252985566] 'range keys from in-memory index tree' (duration: 227.123809ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:37.658864Z","caller":"traceutil/trace.go:171","msg":"trace[1728487688] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"151.652362ms","start":"2026-05-09T21:47:37.507179Z","end":"2026-05-09T21:47:37.658832Z","steps":["trace[1728487688] 'process raft request' (duration: 103.660019ms)","trace[1728487688] 'compare' (duration: 47.709216ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:38.442661Z","caller":"traceutil/trace.go:171","msg":"trace[1210358136] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"114.81259ms","start":"2026-05-09T21:47:38.327821Z","end":"2026-05-09T21:47:38.442634Z","steps":["trace[1210358136] 'process raft request' (duration: 73.428071ms)","trace[1210358136] 'compare' (duration: 41.081661ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:38.56642Z","caller":"traceutil/trace.go:171","msg":"trace[1547698977] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"107.300462ms","start":"2026-05-09T21:47:38.459088Z","end":"2026-05-09T21:47:38.566389Z","steps":["trace[1547698977] 'process raft request' (duration: 89.102474ms)","trace[1547698977] 'compare' (duration: 17.852159ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:23.802667Z","caller":"traceutil/trace.go:171","msg":"trace[1516908340] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"111.707392ms","start":"2026-05-09T21:48:23.690939Z","end":"2026-05-09T21:48:23.802646Z","steps":["trace[1516908340] 'process raft request' (duration: 111.563228ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:06.28773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3276} {"level":"info","ts":"2026-05-09T21:51:06.447965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3276,"took":"153.604227ms","hash":3453307979,"current-db-size-bytes":59039744,"current-db-size":"59 MB","current-db-size-in-use-bytes":32534528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T21:51:06.448038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3453307979,"revision":3276,"compact-revision":-1} {"level":"info","ts":"2026-05-09T21:51:30.696334Z","caller":"traceutil/trace.go:171","msg":"trace[1038917881] linearizableReadLoop","detail":"{readStateIndex:7008; appliedIndex:7007; }","duration":"104.391116ms","start":"2026-05-09T21:51:30.591904Z","end":"2026-05-09T21:51:30.696295Z","steps":["trace[1038917881] 'read index received' (duration: 104.218762ms)","trace[1038917881] 'applied index is now lower than readState.Index' (duration: 171.654µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:30.696598Z","caller":"traceutil/trace.go:171","msg":"trace[1809940686] transaction","detail":"{read_only:false; response_revision:6704; number_of_response:1; }","duration":"188.919303ms","start":"2026-05-09T21:51:30.507644Z","end":"2026-05-09T21:51:30.696563Z","steps":["trace[1809940686] 'process raft request' (duration: 188.540025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:30.696773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.822637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:51:30.697458Z","caller":"traceutil/trace.go:171","msg":"trace[1416839351] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:6704; }","duration":"105.455431ms","start":"2026-05-09T21:51:30.59186Z","end":"2026-05-09T21:51:30.697315Z","steps":["trace[1416839351] 'agreement among raft nodes before linearized reading' (duration: 104.745534ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:30.813278Z","caller":"traceutil/trace.go:171","msg":"trace[472089648] transaction","detail":"{read_only:false; response_revision:6705; number_of_response:1; }","duration":"109.132419ms","start":"2026-05-09T21:51:30.704109Z","end":"2026-05-09T21:51:30.813241Z","steps":["trace[472089648] 'process raft request' (duration: 99.221754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:54:46.793221Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495935029676882,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:54:47.293945Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495935029676882,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:54:47.79671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495935029676882,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:54:47.845885Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.587740652s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T21:54:47.847799Z","caller":"traceutil/trace.go:171","msg":"trace[187824064] transaction","detail":"{read_only:false; response_revision:7996; number_of_response:1; }","duration":"1.589703658s","start":"2026-05-09T21:54:46.258074Z","end":"2026-05-09T21:54:47.847778Z","steps":["trace[187824064] 'process raft request' (duration: 1.588157972s)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:54:47.848045Z","caller":"traceutil/trace.go:171","msg":"trace[1573361489] linearizableReadLoop","detail":"{readStateIndex:8379; appliedIndex:8376; }","duration":"1.554846858s","start":"2026-05-09T21:54:46.293176Z","end":"2026-05-09T21:54:47.848023Z","steps":["trace[1573361489] 'read index received' (duration: 1.553039116s)","trace[1573361489] 'applied index is now lower than readState.Index' (duration: 1.806942ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:54:47.848337Z","caller":"traceutil/trace.go:171","msg":"trace[1765438016] transaction","detail":"{read_only:false; response_revision:7997; number_of_response:1; }","duration":"672.264491ms","start":"2026-05-09T21:54:47.17606Z","end":"2026-05-09T21:54:47.848325Z","steps":["trace[1765438016] 'process raft request' (duration: 671.678787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:54:47.848433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:54:47.176038Z","time spent":"672.329353ms","remote":"127.0.0.1:54096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:54:47.84854Z","caller":"traceutil/trace.go:171","msg":"trace[1177238681] transaction","detail":"{read_only:false; response_revision:7998; number_of_response:1; }","duration":"587.950563ms","start":"2026-05-09T21:54:47.26058Z","end":"2026-05-09T21:54:47.848531Z","steps":["trace[1177238681] 'process raft request' (duration: 587.38614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:54:47.848584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:54:47.260552Z","time spent":"588.007564ms","remote":"127.0.0.1:54096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:54:47.848726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.555557375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:971"} {"level":"info","ts":"2026-05-09T21:54:47.848765Z","caller":"traceutil/trace.go:171","msg":"trace[1342728873] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:7998; }","duration":"1.555637727s","start":"2026-05-09T21:54:46.293118Z","end":"2026-05-09T21:54:47.848755Z","steps":["trace[1342728873] 'agreement among raft nodes before linearized reading' (duration: 1.555524574s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:54:47.8488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:54:46.293098Z","time spent":"1.555696038s","remote":"127.0.0.1:54024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":994,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-05-09T21:54:47.848935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536722607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:986"} {"level":"info","ts":"2026-05-09T21:54:47.848974Z","caller":"traceutil/trace.go:171","msg":"trace[1497929589] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:7998; }","duration":"1.536824659s","start":"2026-05-09T21:54:46.312136Z","end":"2026-05-09T21:54:47.84896Z","steps":["trace[1497929589] 'agreement among raft nodes before linearized reading' (duration: 1.536666426s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:54:47.848951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:54:46.258063Z","time spent":"1.5898256s","remote":"127.0.0.1:54096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:54:47.848994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:54:46.31211Z","time spent":"1.536878781s","remote":"127.0.0.1:54024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1009,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" "} {"level":"warn","ts":"2026-05-09T21:54:47.84925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.502473072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/envoy-gateway-system/\" range_end:\"/registry/statefulsets/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:54:47.849273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497971877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/ingress-nginx/\" range_end:\"/registry/services/endpoints/ingress-nginx0\" limit:500 ","response":"range_response_count:4 size:4729"} {"level":"warn","ts":"2026-05-09T21:54:47.849269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353709976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-09T21:54:47.849279Z","caller":"traceutil/trace.go:171","msg":"trace[133920757] range","detail":"{range_begin:/registry/statefulsets/envoy-gateway-system/; range_end:/registry/statefulsets/envoy-gateway-system0; response_count:0; response_revision:7998; }","duration":"1.502543744s","start":"2026-05-09T21:54:46.346727Z","end":"2026-05-09T21:54:47.849271Z","steps":["trace[133920757] 'agreement among raft nodes before linearized reading' (duration: 1.502493522s)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:54:47.849298Z","caller":"traceutil/trace.go:171","msg":"trace[1288358111] range","detail":"{range_begin:/registry/services/endpoints/ingress-nginx/; range_end:/registry/services/endpoints/ingress-nginx0; response_count:4; response_revision:7998; }","duration":"1.497997848s","start":"2026-05-09T21:54:46.351292Z","end":"2026-05-09T21:54:47.84929Z","steps":["trace[1288358111] 'agreement among raft nodes before linearized reading' (duration: 1.497924526s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:54:47.849307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:54:46.346707Z","time spent":"1.502594034s","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/envoy-gateway-system/\" range_end:\"/registry/statefulsets/envoy-gateway-system0\" limit:500 "} {"level":"info","ts":"2026-05-09T21:54:47.849305Z","caller":"traceutil/trace.go:171","msg":"trace[529682369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7998; }","duration":"1.353785208s","start":"2026-05-09T21:54:46.49551Z","end":"2026-05-09T21:54:47.849295Z","steps":["trace[529682369] 'agreement among raft nodes before linearized reading' (duration: 1.353655345s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:54:47.849316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:54:46.351254Z","time spent":"1.49805781s","remote":"127.0.0.1:53984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":4,"response size":4752,"request content":"key:\"/registry/services/endpoints/ingress-nginx/\" range_end:\"/registry/services/endpoints/ingress-nginx0\" limit:500 "} {"level":"warn","ts":"2026-05-09T21:54:47.849336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:54:46.495492Z","time spent":"1.35383722s","remote":"127.0.0.1:54096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T21:54:47.849402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.059839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:54:47.849449Z","caller":"traceutil/trace.go:171","msg":"trace[389977310] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:7998; }","duration":"828.156601ms","start":"2026-05-09T21:54:47.021282Z","end":"2026-05-09T21:54:47.849439Z","steps":["trace[389977310] 'agreement among raft nodes before linearized reading' (duration: 828.064379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:54:47.849497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234237163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-05-09T21:54:47.8495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:54:47.021264Z","time spent":"828.228833ms","remote":"127.0.0.1:53914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-05-09T21:54:47.849521Z","caller":"traceutil/trace.go:171","msg":"trace[281935554] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7998; }","duration":"1.234288143s","start":"2026-05-09T21:54:46.615224Z","end":"2026-05-09T21:54:47.849512Z","steps":["trace[281935554] 'agreement among raft nodes before linearized reading' (duration: 1.234225702s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:54:47.849539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:54:46.615207Z","time spent":"1.234328115s","remote":"127.0.0.1:53934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "}