{"level":"warn","ts":"2026-10-13T21:10:16.57781Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T21:10:16.577977Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.91: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://162.253.55.91:2380","--initial-cluster=instance=https://162.253.55.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.91: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-10-13T21:10:16.578092Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T21:10:16.578111Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-10-13T21:10:16.578147Z","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-10-13T21:10:16.579426Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"]} {"level":"info","ts":"2026-10-13T21:10:16.579564Z","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://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.91: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-10-13T21:10:16.584562Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.507737ms"} {"level":"info","ts":"2026-10-13T21:10:16.601078Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a5b61ed9120d6b3","cluster-id":"9e0c1e8055c016ad"} {"level":"info","ts":"2026-10-13T21:10:16.601179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T21:10:16.601203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 0"} {"level":"info","ts":"2026-10-13T21:10:16.601216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a5b61ed9120d6b3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T21:10:16.601228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 1"} {"level":"info","ts":"2026-10-13T21:10:16.601269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"warn","ts":"2026-10-13T21:10:16.610803Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T21:10:16.613465Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T21:10:16.614787Z","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-10-13T21:10:16.61674Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a5b61ed9120d6b3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T21:10:16.617604Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a5b61ed9120d6b3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T21:10:16.617854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"info","ts":"2026-10-13T21:10:16.617945Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","added-peer-id":"8a5b61ed9120d6b3","added-peer-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-10-13T21:10:16.617999Z","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-10-13T21:10:16.618035Z","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-10-13T21:10:16.618043Z","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-10-13T21:10:16.621509Z","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-10-13T21:10:16.621708Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a5b61ed9120d6b3","initial-advertise-peer-urls":["https://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T21:10:16.621751Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T21:10:16.62187Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-10-13T21:10:16.621892Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-10-13T21:10:17.303611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T21:10:17.303663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T21:10:17.303711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgPreVoteResp from 8a5b61ed9120d6b3 at term 1"} {"level":"info","ts":"2026-10-13T21:10:17.303729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became candidate at term 2"} {"level":"info","ts":"2026-10-13T21:10:17.303735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgVoteResp from 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-10-13T21:10:17.303742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became leader at term 2"} {"level":"info","ts":"2026-10-13T21:10:17.303748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a5b61ed9120d6b3 elected leader 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-10-13T21:10:17.311692Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T21:10:17.319716Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a5b61ed9120d6b3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.91:2379]}","request-path":"/0/members/8a5b61ed9120d6b3/attributes","cluster-id":"9e0c1e8055c016ad","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T21:10:17.319721Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T21:10:17.319963Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T21:10:17.321543Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.91:2379"} {"level":"info","ts":"2026-10-13T21:10:17.321705Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T21:10:17.321771Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T21:10:17.322636Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T21:10:17.322719Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T21:10:17.322745Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T21:10:17.322919Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T21:10:20.188702Z","caller":"traceutil/trace.go:171","msg":"trace[1800480441] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"373.92777ms","start":"2026-10-13T21:10:19.814735Z","end":"2026-10-13T21:10:20.188663Z","steps":["trace[1800480441] 'process raft request' (duration: 373.787836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:10:20.189334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:10:19.814715Z","time spent":"374.082935ms","remote":"127.0.0.1:43534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T21:10:20.222377Z","caller":"traceutil/trace.go:171","msg":"trace[280413387] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:75; }","duration":"358.025149ms","start":"2026-10-13T21:10:19.864333Z","end":"2026-10-13T21:10:20.222358Z","steps":["trace[280413387] 'read index received' (duration: 324.120045ms)","trace[280413387] 'applied index is now lower than readState.Index' (duration: 33.903774ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:10:20.222463Z","caller":"traceutil/trace.go:171","msg":"trace[671681007] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"406.532356ms","start":"2026-10-13T21:10:19.815922Z","end":"2026-10-13T21:10:20.222455Z","steps":["trace[671681007] 'process raft request' (duration: 406.283308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:10:20.222612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:10:19.815905Z","time spent":"406.580997ms","remote":"127.0.0.1:43644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T21:10:20.222698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.286436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T21:10:20.222854Z","caller":"traceutil/trace.go:171","msg":"trace[436353070] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:72; }","duration":"358.528704ms","start":"2026-10-13T21:10:19.864283Z","end":"2026-10-13T21:10:20.222812Z","steps":["trace[436353070] 'agreement among raft nodes before linearized reading' (duration: 358.229895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:10:20.22298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:10:19.864267Z","time spent":"358.671429ms","remote":"127.0.0.1:43844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-10-13T21:10:20.253915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.73084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T21:10:20.253995Z","caller":"traceutil/trace.go:171","msg":"trace[2047195333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"286.844874ms","start":"2026-10-13T21:10:19.967135Z","end":"2026-10-13T21:10:20.25398Z","steps":["trace[2047195333] 'agreement among raft nodes before linearized reading' (duration: 286.671849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:10:20.254244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.826748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-13T21:10:20.254339Z","caller":"traceutil/trace.go:171","msg":"trace[170859797] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:73; }","duration":"390.001923ms","start":"2026-10-13T21:10:19.864315Z","end":"2026-10-13T21:10:20.254317Z","steps":["trace[170859797] 'agreement among raft nodes before linearized reading' (duration: 389.763196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:10:20.254474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:10:19.864293Z","time spent":"390.134408ms","remote":"127.0.0.1:43862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-10-13T21:11:04.998206Z","caller":"traceutil/trace.go:171","msg":"trace[2144700332] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"408.568623ms","start":"2026-10-13T21:11:04.589572Z","end":"2026-10-13T21:11:04.99814Z","steps":["trace[2144700332] 'process raft request' (duration: 317.347652ms)","trace[2144700332] 'compare' (duration: 90.999043ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:11:04.998542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:11:04.589544Z","time spent":"408.916274ms","remote":"127.0.0.1:43508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T21:11:04.998541Z","caller":"traceutil/trace.go:171","msg":"trace[1019675644] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"407.947382ms","start":"2026-10-13T21:11:04.590551Z","end":"2026-10-13T21:11:04.998499Z","steps":["trace[1019675644] 'read index received' (duration: 316.37399ms)","trace[1019675644] 'applied index is now lower than readState.Index' (duration: 91.569922ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:11:04.998644Z","caller":"traceutil/trace.go:171","msg":"trace[1151105288] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"207.802613ms","start":"2026-10-13T21:11:04.790767Z","end":"2026-10-13T21:11:04.998569Z","steps":["trace[1151105288] 'process raft request' (duration: 207.70972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:11:04.998736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.159829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"info","ts":"2026-10-13T21:11:04.998783Z","caller":"traceutil/trace.go:171","msg":"trace[1340404000] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:646; }","duration":"408.219151ms","start":"2026-10-13T21:11:04.590549Z","end":"2026-10-13T21:11:04.998768Z","steps":["trace[1340404000] 'agreement among raft nodes before linearized reading' (duration: 408.071786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:11:04.998823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:11:04.590526Z","time spent":"408.287113ms","remote":"127.0.0.1:43508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9173,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" "} {"level":"info","ts":"2026-10-13T21:11:04.998986Z","caller":"traceutil/trace.go:171","msg":"trace[500932129] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"395.139912ms","start":"2026-10-13T21:11:04.60382Z","end":"2026-10-13T21:11:04.99896Z","steps":["trace[500932129] 'process raft request' (duration: 394.336836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:11:04.999373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:11:04.603797Z","time spent":"395.255416ms","remote":"127.0.0.1:43508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T21:11:11.549453Z","caller":"traceutil/trace.go:171","msg":"trace[378644367] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"410.045045ms","start":"2026-10-13T21:11:11.139386Z","end":"2026-10-13T21:11:11.549431Z","steps":["trace[378644367] 'process raft request' (duration: 342.734219ms)","trace[378644367] 'compare' (duration: 67.095569ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:11:11.549585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:11:11.139365Z","time spent":"410.154018ms","remote":"127.0.0.1:43504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T21:11:11.600256Z","caller":"traceutil/trace.go:171","msg":"trace[956728746] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:732; }","duration":"272.935214ms","start":"2026-10-13T21:11:11.327295Z","end":"2026-10-13T21:11:11.600231Z","steps":["trace[956728746] 'read index received' (duration: 154.839263ms)","trace[956728746] 'applied index is now lower than readState.Index' (duration: 118.095041ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:11:11.600374Z","caller":"traceutil/trace.go:171","msg":"trace[548441344] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"459.043422ms","start":"2026-10-13T21:11:11.141318Z","end":"2026-10-13T21:11:11.600362Z","steps":["trace[548441344] 'process raft request' (duration: 458.771794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:11:11.600503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:11:11.14131Z","time spent":"459.129385ms","remote":"127.0.0.1:43740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T21:11:11.60057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.62718ms","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-10-13T21:11:11.600655Z","caller":"traceutil/trace.go:171","msg":"trace[1746444006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"257.735164ms","start":"2026-10-13T21:11:11.342902Z","end":"2026-10-13T21:11:11.600637Z","steps":["trace[1746444006] 'agreement among raft nodes before linearized reading' (duration: 257.553708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:11:11.600795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.497562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j74f4\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-13T21:11:11.600828Z","caller":"traceutil/trace.go:171","msg":"trace[689466475] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j74f4; range_end:; response_count:1; response_revision:711; }","duration":"273.558194ms","start":"2026-10-13T21:11:11.327258Z","end":"2026-10-13T21:11:11.600817Z","steps":["trace[689466475] 'agreement among raft nodes before linearized reading' (duration: 273.504423ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:11:12.009722Z","caller":"traceutil/trace.go:171","msg":"trace[949600135] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"404.748766ms","start":"2026-10-13T21:11:11.604947Z","end":"2026-10-13T21:11:12.009696Z","steps":["trace[949600135] 'read index received' (duration: 346.827281ms)","trace[949600135] 'applied index is now lower than readState.Index' (duration: 57.919744ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:11:12.009821Z","caller":"traceutil/trace.go:171","msg":"trace[1413636399] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"404.925981ms","start":"2026-10-13T21:11:11.604883Z","end":"2026-10-13T21:11:12.009809Z","steps":["trace[1413636399] 'process raft request' (duration: 346.794199ms)","trace[1413636399] 'compare' (duration: 57.851023ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:11:12.00991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:11:11.604866Z","time spent":"404.977353ms","remote":"127.0.0.1:43740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T21:11:12.009933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.964522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-13T21:11:12.010012Z","caller":"traceutil/trace.go:171","msg":"trace[1702257185] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:712; }","duration":"405.072716ms","start":"2026-10-13T21:11:11.604923Z","end":"2026-10-13T21:11:12.009995Z","steps":["trace[1702257185] 'agreement among raft nodes before linearized reading' (duration: 404.948592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:11:12.01005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:11:11.604911Z","time spent":"405.131189ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-10-13T21:11:12.309005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.475485ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:11:12.309142Z","caller":"traceutil/trace.go:171","msg":"trace[362817512] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:712; }","duration":"100.649491ms","start":"2026-10-13T21:11:12.208459Z","end":"2026-10-13T21:11:12.309108Z","steps":["trace[362817512] 'range keys from in-memory index tree' (duration: 100.455065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:11:12.309187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.311121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:11:12.309408Z","caller":"traceutil/trace.go:171","msg":"trace[171050369] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:735; }","duration":"299.598278ms","start":"2026-10-13T21:11:12.009777Z","end":"2026-10-13T21:11:12.309375Z","steps":["trace[171050369] 'read index received' (duration: 102.808391ms)","trace[171050369] 'applied index is now lower than readState.Index' (duration: 196.788967ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:11:12.309414Z","caller":"traceutil/trace.go:171","msg":"trace[207871069] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"692.636516ms","start":"2026-10-13T21:11:11.616747Z","end":"2026-10-13T21:11:12.309383Z","steps":["trace[207871069] 'process raft request' (duration: 692.560714ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:11:12.309442Z","caller":"traceutil/trace.go:171","msg":"trace[1755207472] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"704.217559ms","start":"2026-10-13T21:11:11.605204Z","end":"2026-10-13T21:11:12.309422Z","steps":["trace[1755207472] 'process raft request' (duration: 507.440903ms)","trace[1755207472] 'compare' (duration: 196.223878ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:11:12.30948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:11:11.616732Z","time spent":"692.721549ms","remote":"127.0.0.1:43644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T21:11:12.30953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.791747ms","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":"warn","ts":"2026-10-13T21:11:12.309548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:11:11.605188Z","time spent":"704.293321ms","remote":"127.0.0.1:43740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T21:11:12.30956Z","caller":"traceutil/trace.go:171","msg":"trace[269831467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:714; }","duration":"474.848989ms","start":"2026-10-13T21:11:11.834702Z","end":"2026-10-13T21:11:12.309551Z","steps":["trace[269831467] 'agreement among raft nodes before linearized reading' (duration: 474.738655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:11:12.309568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.910533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-13T21:11:12.309588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:11:11.834686Z","time spent":"474.89555ms","remote":"127.0.0.1:43740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-13T21:11:12.309635Z","caller":"traceutil/trace.go:171","msg":"trace[798811865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"420.997925ms","start":"2026-10-13T21:11:11.88862Z","end":"2026-10-13T21:11:12.309618Z","steps":["trace[798811865] 'agreement among raft nodes before linearized reading' (duration: 420.801838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:11:12.309678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:11:11.88861Z","time spent":"421.056127ms","remote":"127.0.0.1:43740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-13T21:11:29.774039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.787226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T21:11:29.774089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.365593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gps6b\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-10-13T21:11:29.774122Z","caller":"traceutil/trace.go:171","msg":"trace[1538042429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:829; }","duration":"250.92067ms","start":"2026-10-13T21:11:29.523183Z","end":"2026-10-13T21:11:29.774103Z","steps":["trace[1538042429] 'range keys from in-memory index tree' (duration: 250.690552ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:11:29.774154Z","caller":"traceutil/trace.go:171","msg":"trace[16884959] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gps6b; range_end:; response_count:1; response_revision:829; }","duration":"342.451775ms","start":"2026-10-13T21:11:29.431671Z","end":"2026-10-13T21:11:29.774123Z","steps":["trace[16884959] 'range keys from in-memory index tree' (duration: 342.213038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:11:29.774186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:11:29.431651Z","time spent":"342.526228ms","remote":"127.0.0.1:43644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gps6b\" "} {"level":"info","ts":"2026-10-13T21:12:14.732375Z","caller":"traceutil/trace.go:171","msg":"trace[592012157] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"120.561984ms","start":"2026-10-13T21:12:14.611785Z","end":"2026-10-13T21:12:14.732346Z","steps":["trace[592012157] 'process raft request' (duration: 119.882162ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:12:25.745453Z","caller":"traceutil/trace.go:171","msg":"trace[656173669] linearizableReadLoop","detail":"{readStateIndex:1596; appliedIndex:1595; }","duration":"102.744014ms","start":"2026-10-13T21:12:25.642682Z","end":"2026-10-13T21:12:25.745426Z","steps":["trace[656173669] 'read index received' (duration: 97.127188ms)","trace[656173669] 'applied index is now lower than readState.Index' (duration: 5.615696ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:12:25.745592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.893099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/policies.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:12:25.745586Z","caller":"traceutil/trace.go:171","msg":"trace[236742820] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"104.889014ms","start":"2026-10-13T21:12:25.640668Z","end":"2026-10-13T21:12:25.745557Z","steps":["trace[236742820] 'process raft request' (duration: 99.177015ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:12:25.745638Z","caller":"traceutil/trace.go:171","msg":"trace[2059675144] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/policies.rabbitmq.com; range_end:; response_count:0; response_revision:1550; }","duration":"102.979761ms","start":"2026-10-13T21:12:25.642642Z","end":"2026-10-13T21:12:25.745622Z","steps":["trace[2059675144] 'agreement among raft nodes before linearized reading' (duration: 102.895899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:12:47.403221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.916533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-19e63e1d-43dc-4a0a-b8fa-707f4eba8bae\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-10-13T21:12:47.403333Z","caller":"traceutil/trace.go:171","msg":"trace[1996694900] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-19e63e1d-43dc-4a0a-b8fa-707f4eba8bae; range_end:; response_count:1; response_revision:1898; }","duration":"297.077775ms","start":"2026-10-13T21:12:47.106229Z","end":"2026-10-13T21:12:47.403306Z","steps":["trace[1996694900] 'range keys from in-memory index tree' (duration: 296.715169ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:13:22.250429Z","caller":"traceutil/trace.go:171","msg":"trace[1369732008] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2219; }","duration":"172.211593ms","start":"2026-10-13T21:13:22.078194Z","end":"2026-10-13T21:13:22.250406Z","steps":["trace[1369732008] 'read index received' (duration: 172.07802ms)","trace[1369732008] 'applied index is now lower than readState.Index' (duration: 132.373µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:13:22.250488Z","caller":"traceutil/trace.go:171","msg":"trace[571481968] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"192.193139ms","start":"2026-10-13T21:13:22.058258Z","end":"2026-10-13T21:13:22.250451Z","steps":["trace[571481968] 'process raft request' (duration: 192.004825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:13:22.25071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.908044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-10-13T21:13:22.250749Z","caller":"traceutil/trace.go:171","msg":"trace[19973151] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2142; }","duration":"132.998686ms","start":"2026-10-13T21:13:22.117738Z","end":"2026-10-13T21:13:22.250737Z","steps":["trace[19973151] 'agreement among raft nodes before linearized reading' (duration: 132.855133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:13:22.250727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.507709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-13T21:13:22.250852Z","caller":"traceutil/trace.go:171","msg":"trace[1178885428] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2142; }","duration":"172.694223ms","start":"2026-10-13T21:13:22.078136Z","end":"2026-10-13T21:13:22.25083Z","steps":["trace[1178885428] 'agreement among raft nodes before linearized reading' (duration: 172.431407ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:13:22.490421Z","caller":"traceutil/trace.go:171","msg":"trace[1293782958] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"235.699283ms","start":"2026-10-13T21:13:22.254698Z","end":"2026-10-13T21:13:22.490397Z","steps":["trace[1293782958] 'process raft request' (duration: 235.55735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:13:22.775782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.502903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:13:22.776086Z","caller":"traceutil/trace.go:171","msg":"trace[258388427] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"281.415782ms","start":"2026-10-13T21:13:22.494639Z","end":"2026-10-13T21:13:22.776055Z","steps":["trace[258388427] 'process raft request' (duration: 63.540602ms)","trace[258388427] 'compare' (duration: 217.35409ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:13:22.776398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.027016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:13:22.776123Z","caller":"traceutil/trace.go:171","msg":"trace[1707140929] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2222; }","duration":"252.74321ms","start":"2026-10-13T21:13:22.523359Z","end":"2026-10-13T21:13:22.776102Z","steps":["trace[1707140929] 'read index received' (duration: 34.950732ms)","trace[1707140929] 'applied index is now lower than readState.Index' (duration: 217.790898ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:13:22.776455Z","caller":"traceutil/trace.go:171","msg":"trace[394614536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2145; }","duration":"253.110878ms","start":"2026-10-13T21:13:22.523329Z","end":"2026-10-13T21:13:22.77644Z","steps":["trace[394614536] 'agreement among raft nodes before linearized reading' (duration: 252.867303ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:13:22.776175Z","caller":"traceutil/trace.go:171","msg":"trace[944864934] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"277.166156ms","start":"2026-10-13T21:13:22.498967Z","end":"2026-10-13T21:13:22.776133Z","steps":["trace[944864934] 'process raft request' (duration: 276.928011ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:13:22.783445Z","caller":"traceutil/trace.go:171","msg":"trace[44797685] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"223.25305ms","start":"2026-10-13T21:13:22.560176Z","end":"2026-10-13T21:13:22.78343Z","steps":["trace[44797685] 'process raft request' (duration: 223.103027ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:13:22.783733Z","caller":"traceutil/trace.go:171","msg":"trace[7798774] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"103.489985ms","start":"2026-10-13T21:13:22.680208Z","end":"2026-10-13T21:13:22.783698Z","steps":["trace[7798774] 'process raft request' (duration: 103.185428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:13:22.783878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.670121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:13:22.783941Z","caller":"traceutil/trace.go:171","msg":"trace[1633877417] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2147; }","duration":"257.774952ms","start":"2026-10-13T21:13:22.52615Z","end":"2026-10-13T21:13:22.783925Z","steps":["trace[1633877417] 'agreement among raft nodes before linearized reading' (duration: 257.66413ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:14:27.228594Z","caller":"traceutil/trace.go:171","msg":"trace[1764007315] linearizableReadLoop","detail":"{readStateIndex:2684; appliedIndex:2683; }","duration":"121.359688ms","start":"2026-10-13T21:14:27.10721Z","end":"2026-10-13T21:14:27.22857Z","steps":["trace[1764007315] 'read index received' (duration: 121.144053ms)","trace[1764007315] 'applied index is now lower than readState.Index' (duration: 214.645µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:14:27.228648Z","caller":"traceutil/trace.go:171","msg":"trace[355263245] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"209.168476ms","start":"2026-10-13T21:14:27.01945Z","end":"2026-10-13T21:14:27.228619Z","steps":["trace[355263245] 'process raft request' (duration: 208.92769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:14:27.228866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.648465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-13T21:14:27.228942Z","caller":"traceutil/trace.go:171","msg":"trace[236687953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2590; }","duration":"121.742307ms","start":"2026-10-13T21:14:27.107154Z","end":"2026-10-13T21:14:27.228897Z","steps":["trace[236687953] 'agreement among raft nodes before linearized reading' (duration: 121.549484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:14:27.228974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.660927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-13T21:14:27.229041Z","caller":"traceutil/trace.go:171","msg":"trace[161250198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2590; }","duration":"117.76823ms","start":"2026-10-13T21:14:27.111256Z","end":"2026-10-13T21:14:27.229024Z","steps":["trace[161250198] 'agreement among raft nodes before linearized reading' (duration: 117.595755ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:14:59.121377Z","caller":"traceutil/trace.go:171","msg":"trace[2039314019] transaction","detail":"{read_only:false; response_revision:2768; number_of_response:1; }","duration":"124.152971ms","start":"2026-10-13T21:14:58.99718Z","end":"2026-10-13T21:14:59.121333Z","steps":["trace[2039314019] 'process raft request' (duration: 123.944567ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:24.995906Z","caller":"traceutil/trace.go:171","msg":"trace[263007769] transaction","detail":"{read_only:false; response_revision:4465; number_of_response:1; }","duration":"107.547886ms","start":"2026-10-13T21:19:24.888326Z","end":"2026-10-13T21:19:24.995874Z","steps":["trace[263007769] 'process raft request' (duration: 77.895851ms)","trace[263007769] 'compare' (duration: 29.382097ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:19:24.996132Z","caller":"traceutil/trace.go:171","msg":"trace[253484502] transaction","detail":"{read_only:false; response_revision:4473; number_of_response:1; }","duration":"102.033446ms","start":"2026-10-13T21:19:24.894075Z","end":"2026-10-13T21:19:24.996109Z","steps":["trace[253484502] 'process raft request' (duration: 101.986054ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:24.996399Z","caller":"traceutil/trace.go:171","msg":"trace[292193054] transaction","detail":"{read_only:false; response_revision:4472; number_of_response:1; }","duration":"104.917545ms","start":"2026-10-13T21:19:24.89147Z","end":"2026-10-13T21:19:24.996388Z","steps":["trace[292193054] 'process raft request' (duration: 104.564594ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:24.996398Z","caller":"traceutil/trace.go:171","msg":"trace[1923802736] transaction","detail":"{read_only:false; response_revision:4469; number_of_response:1; }","duration":"105.196274ms","start":"2026-10-13T21:19:24.891167Z","end":"2026-10-13T21:19:24.996363Z","steps":["trace[1923802736] 'process raft request' (duration: 104.773861ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:24.996415Z","caller":"traceutil/trace.go:171","msg":"trace[556063151] transaction","detail":"{read_only:false; response_revision:4471; number_of_response:1; }","duration":"104.973616ms","start":"2026-10-13T21:19:24.891411Z","end":"2026-10-13T21:19:24.996385Z","steps":["trace[556063151] 'process raft request' (duration: 104.598626ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:24.996442Z","caller":"traceutil/trace.go:171","msg":"trace[894454924] transaction","detail":"{read_only:false; response_revision:4470; number_of_response:1; }","duration":"105.041769ms","start":"2026-10-13T21:19:24.891383Z","end":"2026-10-13T21:19:24.996425Z","steps":["trace[894454924] 'process raft request' (duration: 104.583025ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:24.996642Z","caller":"traceutil/trace.go:171","msg":"trace[907586251] transaction","detail":"{read_only:false; response_revision:4467; number_of_response:1; }","duration":"108.160625ms","start":"2026-10-13T21:19:24.888464Z","end":"2026-10-13T21:19:24.996624Z","steps":["trace[907586251] 'process raft request' (duration: 107.392262ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:24.996734Z","caller":"traceutil/trace.go:171","msg":"trace[25504539] transaction","detail":"{read_only:false; response_revision:4468; number_of_response:1; }","duration":"108.782885ms","start":"2026-10-13T21:19:24.887937Z","end":"2026-10-13T21:19:24.99672Z","steps":["trace[25504539] 'process raft request' (duration: 107.96964ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:24.996742Z","caller":"traceutil/trace.go:171","msg":"trace[1073145330] transaction","detail":"{read_only:false; response_revision:4466; number_of_response:1; }","duration":"108.267378ms","start":"2026-10-13T21:19:24.888428Z","end":"2026-10-13T21:19:24.996695Z","steps":["trace[1073145330] 'process raft request' (duration: 107.367571ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:25.340131Z","caller":"traceutil/trace.go:171","msg":"trace[915889361] transaction","detail":"{read_only:false; response_revision:4546; number_of_response:1; }","duration":"133.457197ms","start":"2026-10-13T21:19:25.206659Z","end":"2026-10-13T21:19:25.340116Z","steps":["trace[915889361] 'process raft request' (duration: 133.124976ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:25.340235Z","caller":"traceutil/trace.go:171","msg":"trace[446132538] transaction","detail":"{read_only:false; response_revision:4547; number_of_response:1; }","duration":"133.5705ms","start":"2026-10-13T21:19:25.206659Z","end":"2026-10-13T21:19:25.340229Z","steps":["trace[446132538] 'process raft request' (duration: 133.159477ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:25.340314Z","caller":"traceutil/trace.go:171","msg":"trace[1839904931] transaction","detail":"{read_only:false; response_revision:4548; number_of_response:1; }","duration":"133.586871ms","start":"2026-10-13T21:19:25.206722Z","end":"2026-10-13T21:19:25.340308Z","steps":["trace[1839904931] 'process raft request' (duration: 133.112066ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:25.340251Z","caller":"traceutil/trace.go:171","msg":"trace[1214361539] transaction","detail":"{read_only:false; response_revision:4545; number_of_response:1; }","duration":"133.848059ms","start":"2026-10-13T21:19:25.206373Z","end":"2026-10-13T21:19:25.340221Z","steps":["trace[1214361539] 'process raft request' (duration: 77.117379ms)","trace[1214361539] 'compare' (duration: 56.023378ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:19:25.340374Z","caller":"traceutil/trace.go:171","msg":"trace[946539487] transaction","detail":"{read_only:false; response_revision:4549; number_of_response:1; }","duration":"133.53226ms","start":"2026-10-13T21:19:25.206838Z","end":"2026-10-13T21:19:25.34037Z","steps":["trace[946539487] 'process raft request' (duration: 133.015493ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:25.340478Z","caller":"traceutil/trace.go:171","msg":"trace[1769661792] transaction","detail":"{read_only:false; response_revision:4555; number_of_response:1; }","duration":"109.528718ms","start":"2026-10-13T21:19:25.230943Z","end":"2026-10-13T21:19:25.340472Z","steps":["trace[1769661792] 'process raft request' (duration: 109.009093ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:25.340572Z","caller":"traceutil/trace.go:171","msg":"trace[1851975624] transaction","detail":"{read_only:false; response_revision:4550; number_of_response:1; }","duration":"133.696783ms","start":"2026-10-13T21:19:25.206857Z","end":"2026-10-13T21:19:25.340554Z","steps":["trace[1851975624] 'process raft request' (duration: 133.011423ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:25.340683Z","caller":"traceutil/trace.go:171","msg":"trace[781221238] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"133.772765ms","start":"2026-10-13T21:19:25.206906Z","end":"2026-10-13T21:19:25.340679Z","steps":["trace[781221238] 'process raft request' (duration: 132.977451ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:25.340748Z","caller":"traceutil/trace.go:171","msg":"trace[1593176402] transaction","detail":"{read_only:false; response_revision:4552; number_of_response:1; }","duration":"133.827397ms","start":"2026-10-13T21:19:25.206913Z","end":"2026-10-13T21:19:25.34074Z","steps":["trace[1593176402] 'process raft request' (duration: 132.983092ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:25.340791Z","caller":"traceutil/trace.go:171","msg":"trace[1277804887] transaction","detail":"{read_only:false; response_revision:4553; number_of_response:1; }","duration":"131.181796ms","start":"2026-10-13T21:19:25.209605Z","end":"2026-10-13T21:19:25.340787Z","steps":["trace[1277804887] 'process raft request' (duration: 130.306289ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:25.340829Z","caller":"traceutil/trace.go:171","msg":"trace[1782311034] linearizableReadLoop","detail":"{readStateIndex:4730; appliedIndex:4718; }","duration":"130.384171ms","start":"2026-10-13T21:19:25.210439Z","end":"2026-10-13T21:19:25.340823Z","steps":["trace[1782311034] 'read index received' (duration: 12.807724ms)","trace[1782311034] 'applied index is now lower than readState.Index' (duration: 117.575417ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:19:25.340946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.164257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T21:19:25.340951Z","caller":"traceutil/trace.go:171","msg":"trace[743515403] transaction","detail":"{read_only:false; response_revision:4554; number_of_response:1; }","duration":"113.83394ms","start":"2026-10-13T21:19:25.227102Z","end":"2026-10-13T21:19:25.340936Z","steps":["trace[743515403] 'process raft request' (duration: 112.82733ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:25.340974Z","caller":"traceutil/trace.go:171","msg":"trace[1838816714] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:4556; }","duration":"122.224389ms","start":"2026-10-13T21:19:25.218745Z","end":"2026-10-13T21:19:25.34097Z","steps":["trace[1838816714] 'agreement among raft nodes before linearized reading' (duration: 122.148737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:19:25.340965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.514025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus-node-exporter\" ","response":"range_response_count:1 size:1176"} {"level":"info","ts":"2026-10-13T21:19:25.341132Z","caller":"traceutil/trace.go:171","msg":"trace[964169640] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus-node-exporter; range_end:; response_count:1; response_revision:4556; }","duration":"130.704791ms","start":"2026-10-13T21:19:25.210413Z","end":"2026-10-13T21:19:25.341118Z","steps":["trace[964169640] 'agreement among raft nodes before linearized reading' (duration: 130.475034ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:35.896572Z","caller":"traceutil/trace.go:171","msg":"trace[526534412] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"125.959801ms","start":"2026-10-13T21:19:35.770587Z","end":"2026-10-13T21:19:35.896547Z","steps":["trace[526534412] 'process raft request' (duration: 125.830797ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:41.491211Z","caller":"traceutil/trace.go:171","msg":"trace[882081297] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"152.31202ms","start":"2026-10-13T21:19:41.338873Z","end":"2026-10-13T21:19:41.491185Z","steps":["trace[882081297] 'process raft request' (duration: 98.341871ms)","trace[882081297] 'compare' (duration: 53.839305ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:19:42.037676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.145267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:19:42.037779Z","caller":"traceutil/trace.go:171","msg":"trace[1412847860] linearizableReadLoop","detail":"{readStateIndex:5156; appliedIndex:5155; }","duration":"514.56218ms","start":"2026-10-13T21:19:41.523201Z","end":"2026-10-13T21:19:42.037763Z","steps":["trace[1412847860] 'read index received' (duration: 153.183476ms)","trace[1412847860] 'applied index is now lower than readState.Index' (duration: 361.377713ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:19:42.037834Z","caller":"traceutil/trace.go:171","msg":"trace[1217509736] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"696.777714ms","start":"2026-10-13T21:19:41.341009Z","end":"2026-10-13T21:19:42.037786Z","steps":["trace[1217509736] 'process raft request' (duration: 335.459972ms)","trace[1217509736] 'compare' (duration: 360.865608ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:19:42.037974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:19:41.340982Z","time spent":"696.927609ms","remote":"127.0.0.1:43646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1250,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T21:19:42.038034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.822588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:19:42.038171Z","caller":"traceutil/trace.go:171","msg":"trace[2146005097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4968; }","duration":"514.977003ms","start":"2026-10-13T21:19:41.523144Z","end":"2026-10-13T21:19:42.038121Z","steps":["trace[2146005097] 'agreement among raft nodes before linearized reading' (duration: 514.724955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:19:42.038269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:19:41.523121Z","time spent":"515.097147ms","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T21:19:42.039038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.286559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-7df8fd5cb9-fvpq6.18de338c9fe94cfa\" ","response":"range_response_count:1 size:896"} {"level":"warn","ts":"2026-10-13T21:19:42.039053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.726634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-13T21:19:42.039084Z","caller":"traceutil/trace.go:171","msg":"trace[530770125] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-7df8fd5cb9-fvpq6.18de338c9fe94cfa; range_end:; response_count:1; response_revision:4968; }","duration":"217.377551ms","start":"2026-10-13T21:19:41.821694Z","end":"2026-10-13T21:19:42.039072Z","steps":["trace[530770125] 'agreement among raft nodes before linearized reading' (duration: 217.219736ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.03916Z","caller":"traceutil/trace.go:171","msg":"trace[1782786262] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4968; }","duration":"382.818416ms","start":"2026-10-13T21:19:41.656269Z","end":"2026-10-13T21:19:42.039087Z","steps":["trace[1782786262] 'agreement among raft nodes before linearized reading' (duration: 382.648651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:19:42.039261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:19:41.656247Z","time spent":"382.996962ms","remote":"127.0.0.1:43740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-13T21:19:42.039713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.045763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12972"} {"level":"info","ts":"2026-10-13T21:19:42.039761Z","caller":"traceutil/trace.go:171","msg":"trace[560373224] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4968; }","duration":"218.097915ms","start":"2026-10-13T21:19:41.821651Z","end":"2026-10-13T21:19:42.039749Z","steps":["trace[560373224] 'agreement among raft nodes before linearized reading' (duration: 217.914679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:19:42.041578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.176201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-10-13T21:19:42.041647Z","caller":"traceutil/trace.go:171","msg":"trace[110125409] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4968; }","duration":"127.38912ms","start":"2026-10-13T21:19:41.914245Z","end":"2026-10-13T21:19:42.041634Z","steps":["trace[110125409] 'agreement among raft nodes before linearized reading' (duration: 125.110609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:19:42.172633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.851743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130620"} {"level":"info","ts":"2026-10-13T21:19:42.172705Z","caller":"traceutil/trace.go:171","msg":"trace[971596539] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4974; }","duration":"126.960526ms","start":"2026-10-13T21:19:42.045727Z","end":"2026-10-13T21:19:42.172688Z","steps":["trace[971596539] 'agreement among raft nodes before linearized reading' (duration: 126.710768ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.173054Z","caller":"traceutil/trace.go:171","msg":"trace[201269492] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"118.465363ms","start":"2026-10-13T21:19:42.054577Z","end":"2026-10-13T21:19:42.173042Z","steps":["trace[201269492] 'process raft request' (duration: 117.783652ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.173126Z","caller":"traceutil/trace.go:171","msg":"trace[995232227] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"127.055669ms","start":"2026-10-13T21:19:42.046051Z","end":"2026-10-13T21:19:42.173106Z","steps":["trace[995232227] 'process raft request' (duration: 126.271975ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.173183Z","caller":"traceutil/trace.go:171","msg":"trace[1613509592] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"128.778313ms","start":"2026-10-13T21:19:42.04437Z","end":"2026-10-13T21:19:42.173148Z","steps":["trace[1613509592] 'process raft request' (duration: 102.52521ms)","trace[1613509592] 'compare' (duration: 25.20087ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:19:42.173301Z","caller":"traceutil/trace.go:171","msg":"trace[1140099403] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"127.818623ms","start":"2026-10-13T21:19:42.045471Z","end":"2026-10-13T21:19:42.17329Z","steps":["trace[1140099403] 'process raft request' (duration: 126.75278ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.173946Z","caller":"traceutil/trace.go:171","msg":"trace[419362007] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"126.971657ms","start":"2026-10-13T21:19:42.045984Z","end":"2026-10-13T21:19:42.172956Z","steps":["trace[419362007] 'process raft request' (duration: 126.301226ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.39807Z","caller":"traceutil/trace.go:171","msg":"trace[374974654] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"189.123439ms","start":"2026-10-13T21:19:42.208925Z","end":"2026-10-13T21:19:42.398048Z","steps":["trace[374974654] 'process raft request' (duration: 189.081318ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.398725Z","caller":"traceutil/trace.go:171","msg":"trace[1733294287] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"211.249753ms","start":"2026-10-13T21:19:42.187457Z","end":"2026-10-13T21:19:42.398707Z","steps":["trace[1733294287] 'process raft request' (duration: 210.468979ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.398847Z","caller":"traceutil/trace.go:171","msg":"trace[535274931] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"212.233603ms","start":"2026-10-13T21:19:42.186604Z","end":"2026-10-13T21:19:42.398838Z","steps":["trace[535274931] 'process raft request' (duration: 211.271373ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.399085Z","caller":"traceutil/trace.go:171","msg":"trace[2103644242] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"212.645636ms","start":"2026-10-13T21:19:42.186406Z","end":"2026-10-13T21:19:42.399051Z","steps":["trace[2103644242] 'process raft request' (duration: 211.326726ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.399206Z","caller":"traceutil/trace.go:171","msg":"trace[1096529746] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"208.974352ms","start":"2026-10-13T21:19:42.19022Z","end":"2026-10-13T21:19:42.399194Z","steps":["trace[1096529746] 'process raft request' (duration: 207.746494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:19:42.72234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.971216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:19:42.722824Z","caller":"traceutil/trace.go:171","msg":"trace[1339922824] linearizableReadLoop","detail":"{readStateIndex:5171; appliedIndex:5168; }","duration":"199.416598ms","start":"2026-10-13T21:19:42.523389Z","end":"2026-10-13T21:19:42.722806Z","steps":["trace[1339922824] 'read index received' (duration: 83.918626ms)","trace[1339922824] 'applied index is now lower than readState.Index' (duration: 115.497092ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:19:42.722988Z","caller":"traceutil/trace.go:171","msg":"trace[672870977] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"218.323021ms","start":"2026-10-13T21:19:42.504633Z","end":"2026-10-13T21:19:42.722956Z","steps":["trace[672870977] 'process raft request' (duration: 217.839607ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.722995Z","caller":"traceutil/trace.go:171","msg":"trace[1297071307] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"469.349574ms","start":"2026-10-13T21:19:42.25362Z","end":"2026-10-13T21:19:42.72297Z","steps":["trace[1297071307] 'process raft request' (duration: 353.680207ms)","trace[1297071307] 'compare' (duration: 114.696597ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:19:42.723077Z","caller":"traceutil/trace.go:171","msg":"trace[1021025282] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"217.382762ms","start":"2026-10-13T21:19:42.505666Z","end":"2026-10-13T21:19:42.723049Z","steps":["trace[1021025282] 'process raft request' (duration: 217.097854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:19:42.723148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:19:42.253609Z","time spent":"469.473577ms","remote":"127.0.0.1:43646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1436,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T21:19:42.723264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.742596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:19:42.724068Z","caller":"traceutil/trace.go:171","msg":"trace[1102169485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4983; }","duration":"200.706626ms","start":"2026-10-13T21:19:42.523344Z","end":"2026-10-13T21:19:42.724051Z","steps":["trace[1102169485] 'agreement among raft nodes before linearized reading' (duration: 199.716756ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.902303Z","caller":"traceutil/trace.go:171","msg":"trace[1501466118] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"174.159075ms","start":"2026-10-13T21:19:42.728103Z","end":"2026-10-13T21:19:42.902262Z","steps":["trace[1501466118] 'process raft request' (duration: 173.99255ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.902483Z","caller":"traceutil/trace.go:171","msg":"trace[1261699808] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"173.399812ms","start":"2026-10-13T21:19:42.729057Z","end":"2026-10-13T21:19:42.902456Z","steps":["trace[1261699808] 'process raft request' (duration: 173.135504ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.902323Z","caller":"traceutil/trace.go:171","msg":"trace[1527526461] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"174.099134ms","start":"2026-10-13T21:19:42.728209Z","end":"2026-10-13T21:19:42.902308Z","steps":["trace[1527526461] 'process raft request' (duration: 173.928738ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:42.902201Z","caller":"traceutil/trace.go:171","msg":"trace[138120657] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"175.096414ms","start":"2026-10-13T21:19:42.727076Z","end":"2026-10-13T21:19:42.902173Z","steps":["trace[138120657] 'process raft request' (duration: 174.878599ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:43.030669Z","caller":"traceutil/trace.go:171","msg":"trace[53940477] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"120.192597ms","start":"2026-10-13T21:19:42.91045Z","end":"2026-10-13T21:19:43.030642Z","steps":["trace[53940477] 'process raft request' (duration: 66.93917ms)","trace[53940477] 'compare' (duration: 53.132473ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:19:43.043642Z","caller":"traceutil/trace.go:171","msg":"trace[516605103] linearizableReadLoop","detail":"{readStateIndex:5178; appliedIndex:5176; }","duration":"128.644418ms","start":"2026-10-13T21:19:42.914978Z","end":"2026-10-13T21:19:43.043622Z","steps":["trace[516605103] 'read index received' (duration: 62.541374ms)","trace[516605103] 'applied index is now lower than readState.Index' (duration: 66.101624ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:19:43.043748Z","caller":"traceutil/trace.go:171","msg":"trace[1145354544] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"132.356414ms","start":"2026-10-13T21:19:42.911379Z","end":"2026-10-13T21:19:43.043736Z","steps":["trace[1145354544] 'process raft request' (duration: 132.105296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:19:43.043993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.992569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-10-13T21:19:43.044063Z","caller":"traceutil/trace.go:171","msg":"trace[380623618] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4990; }","duration":"129.112953ms","start":"2026-10-13T21:19:42.914931Z","end":"2026-10-13T21:19:43.044044Z","steps":["trace[380623618] 'agreement among raft nodes before linearized reading' (duration: 128.842855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:19:43.047369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.253206ms","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-10-13T21:19:43.048199Z","caller":"traceutil/trace.go:171","msg":"trace[2040559607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4991; }","duration":"107.547476ms","start":"2026-10-13T21:19:42.940631Z","end":"2026-10-13T21:19:43.048179Z","steps":["trace[2040559607] 'agreement among raft nodes before linearized reading' (duration: 104.736069ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:56.195342Z","caller":"traceutil/trace.go:171","msg":"trace[19103823] linearizableReadLoop","detail":"{readStateIndex:5391; appliedIndex:5390; }","duration":"126.85484ms","start":"2026-10-13T21:19:56.068466Z","end":"2026-10-13T21:19:56.195321Z","steps":["trace[19103823] 'read index received' (duration: 109.73443ms)","trace[19103823] 'applied index is now lower than readState.Index' (duration: 17.11931ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:19:56.195415Z","caller":"traceutil/trace.go:171","msg":"trace[311726094] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"198.991315ms","start":"2026-10-13T21:19:55.996395Z","end":"2026-10-13T21:19:56.195386Z","steps":["trace[311726094] 'process raft request' (duration: 181.885595ms)","trace[311726094] 'compare' (duration: 16.807831ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:19:56.195681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.201241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-13T21:19:56.195748Z","caller":"traceutil/trace.go:171","msg":"trace[940915689] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5199; }","duration":"127.299914ms","start":"2026-10-13T21:19:56.068417Z","end":"2026-10-13T21:19:56.195717Z","steps":["trace[940915689] 'agreement among raft nodes before linearized reading' (duration: 127.031875ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:56.406073Z","caller":"traceutil/trace.go:171","msg":"trace[2048402443] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"107.664256ms","start":"2026-10-13T21:19:56.29838Z","end":"2026-10-13T21:19:56.406044Z","steps":["trace[2048402443] 'process raft request' (duration: 23.954773ms)","trace[2048402443] 'compare' (duration: 83.6083ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:19:56.968892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.122459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:19:56.969313Z","caller":"traceutil/trace.go:171","msg":"trace[1166433125] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"147.120809ms","start":"2026-10-13T21:19:56.822145Z","end":"2026-10-13T21:19:56.969265Z","steps":["trace[1166433125] 'process raft request' (duration: 29.554566ms)","trace[1166433125] 'compare' (duration: 116.961534ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:19:57.174879Z","caller":"traceutil/trace.go:171","msg":"trace[516701770] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"110.998689ms","start":"2026-10-13T21:19:57.063854Z","end":"2026-10-13T21:19:57.174852Z","steps":["trace[516701770] 'process raft request' (duration: 110.777052ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:20:17.349721Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2863} {"level":"info","ts":"2026-10-13T21:20:17.440898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2863,"took":"89.801449ms","hash":1231580385,"current-db-size-bytes":53104640,"current-db-size":"53 MB","current-db-size-in-use-bytes":28291072,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-13T21:20:17.440961Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1231580385,"revision":2863,"compact-revision":-1} {"level":"info","ts":"2026-10-13T21:20:31.185384Z","caller":"traceutil/trace.go:171","msg":"trace[1194779111] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"118.266049ms","start":"2026-10-13T21:20:31.067029Z","end":"2026-10-13T21:20:31.185295Z","steps":["trace[1194779111] 'process raft request' (duration: 35.812143ms)","trace[1194779111] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; req_size:7435; } (duration: 82.28488ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:25:17.362015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5348} {"level":"info","ts":"2026-10-13T21:25:17.45674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5348,"took":"93.225504ms","hash":4225243701,"current-db-size-bytes":53104640,"current-db-size":"53 MB","current-db-size-in-use-bytes":18956288,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-13T21:25:17.456922Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4225243701,"revision":5348,"compact-revision":2863}