{"level":"warn","ts":"2026-04-07T05:45:43.362958Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T05:45:43.363043Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T05:45:43.363127Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T05:45:43.363141Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-04-07T05:45:43.36316Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T05:45:43.363782Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-04-07T05:45:43.363885Z","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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T05:45:43.369319Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.1905ms"} {"level":"info","ts":"2026-04-07T05:45:43.378703Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-04-07T05:45:43.378764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T05:45:43.378782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-04-07T05:45:43.378803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T05:45:43.378812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-04-07T05:45:43.37884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-04-07T05:45:43.388062Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T05:45:43.391039Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T05:45:43.39208Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T05:45:43.398218Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T05:45:43.398601Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T05:45:43.398677Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T05:45:43.398688Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T05:45:43.398605Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T05:45:43.404217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-04-07T05:45:43.405056Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-04-07T05:45:43.406604Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T05:45:43.406791Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T05:45:43.406825Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T05:45:43.406927Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-04-07T05:45:43.406946Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-04-07T05:45:43.579542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T05:45:43.579652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T05:45:43.579678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-04-07T05:45:43.579698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-04-07T05:45:43.579723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-04-07T05:45:43.579845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-04-07T05:45:43.579893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-04-07T05:45:43.581582Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T05:45:43.581861Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T05:45:43.581905Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T05:45:43.582051Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:45:43.582181Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T05:45:43.582243Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T05:45:43.585305Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T05:45:43.586196Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:45:43.586302Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:45:43.586341Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:45:43.586771Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-04-07T05:46:12.811225Z","caller":"traceutil/trace.go:171","msg":"trace[157910334] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"402.334345ms","start":"2026-04-07T05:46:12.408858Z","end":"2026-04-07T05:46:12.811192Z","steps":["trace[157910334] 'process raft request' (duration: 402.078115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:12.811881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.408838Z","time spent":"402.488668ms","remote":"127.0.0.1:45454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20851,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:46:12.8648Z","caller":"traceutil/trace.go:171","msg":"trace[331760297] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:556; }","duration":"367.227067ms","start":"2026-04-07T05:46:12.497551Z","end":"2026-04-07T05:46:12.864778Z","steps":["trace[331760297] 'read index received' (duration: 313.557315ms)","trace[331760297] 'applied index is now lower than readState.Index' (duration: 53.668442ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:12.864892Z","caller":"traceutil/trace.go:171","msg":"trace[68296609] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"432.939307ms","start":"2026-04-07T05:46:12.431916Z","end":"2026-04-07T05:46:12.864855Z","steps":["trace[68296609] 'process raft request' (duration: 432.650794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:12.864977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.412971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:12.865067Z","caller":"traceutil/trace.go:171","msg":"trace[1560595364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"367.544852ms","start":"2026-04-07T05:46:12.497507Z","end":"2026-04-07T05:46:12.865051Z","steps":["trace[1560595364] 'agreement among raft nodes before linearized reading' (duration: 367.378169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:12.865064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.43189Z","time spent":"433.085128ms","remote":"127.0.0.1:45454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:46:12.865109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:12.497494Z","time spent":"367.603627ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T05:46:12.997648Z","caller":"traceutil/trace.go:171","msg":"trace[177772948] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"127.901898ms","start":"2026-04-07T05:46:12.869707Z","end":"2026-04-07T05:46:12.997609Z","steps":["trace[177772948] 'process raft request' (duration: 51.477559ms)","trace[177772948] 'compare' (duration: 76.04868ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:13.213978Z","caller":"traceutil/trace.go:171","msg":"trace[1004999219] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"159.241532ms","start":"2026-04-07T05:46:13.054706Z","end":"2026-04-07T05:46:13.213947Z","steps":["trace[1004999219] 'process raft request' (duration: 69.192853ms)","trace[1004999219] 'compare' (duration: 87.256725ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:15.125459Z","caller":"traceutil/trace.go:171","msg":"trace[1116412121] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"131.0962ms","start":"2026-04-07T05:46:14.994335Z","end":"2026-04-07T05:46:15.125431Z","steps":["trace[1116412121] 'process raft request' (duration: 128.029302ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:15.125454Z","caller":"traceutil/trace.go:171","msg":"trace[1570696362] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"121.145148ms","start":"2026-04-07T05:46:15.004271Z","end":"2026-04-07T05:46:15.125416Z","steps":["trace[1570696362] 'read index received' (duration: 118.126903ms)","trace[1570696362] 'applied index is now lower than readState.Index' (duration: 3.016044ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:15.125703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.401498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T05:46:15.125802Z","caller":"traceutil/trace.go:171","msg":"trace[2138496906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"121.563291ms","start":"2026-04-07T05:46:15.004227Z","end":"2026-04-07T05:46:15.12579Z","steps":["trace[2138496906] 'agreement among raft nodes before linearized reading' (duration: 121.255996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:18.166738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.778342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:46:18.167054Z","caller":"traceutil/trace.go:171","msg":"trace[1541057204] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"359.343704ms","start":"2026-04-07T05:46:17.807693Z","end":"2026-04-07T05:46:18.167036Z","steps":["trace[1541057204] 'process raft request' (duration: 145.065811ms)","trace[1541057204] 'compare' (duration: 213.622609ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:18.167109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:17.807667Z","time spent":"359.42072ms","remote":"127.0.0.1:45706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:46:20.046894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.764263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:46:20.047393Z","caller":"traceutil/trace.go:171","msg":"trace[1855213676] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"385.275014ms","start":"2026-04-07T05:46:19.662096Z","end":"2026-04-07T05:46:20.047371Z","steps":["trace[1855213676] 'process raft request' (duration: 135.956795ms)","trace[1855213676] 'compare' (duration: 248.533044ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:20.047465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:19.662085Z","time spent":"385.34336ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:46:20.679536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.042796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:46:20.680148Z","caller":"traceutil/trace.go:171","msg":"trace[1289578797] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"627.712458ms","start":"2026-04-07T05:46:20.052409Z","end":"2026-04-07T05:46:20.680122Z","steps":["trace[1289578797] 'process raft request' (duration: 383.011788ms)","trace[1289578797] 'compare' (duration: 243.903814ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:46:20.680238Z","caller":"traceutil/trace.go:171","msg":"trace[394635063] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:609; }","duration":"420.246926ms","start":"2026-04-07T05:46:20.25997Z","end":"2026-04-07T05:46:20.680217Z","steps":["trace[394635063] 'read index received' (duration: 175.400224ms)","trace[394635063] 'applied index is now lower than readState.Index' (duration: 244.845722ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:46:20.68026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:20.052397Z","time spent":"627.812737ms","remote":"127.0.0.1:45494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:46:20.680499Z","caller":"traceutil/trace.go:171","msg":"trace[347294109] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"628.017684ms","start":"2026-04-07T05:46:20.052471Z","end":"2026-04-07T05:46:20.680488Z","steps":["trace[347294109] 'process raft request' (duration: 627.584008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:20.680589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:20.052329Z","time spent":"628.20955ms","remote":"127.0.0.1:45606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:46:20.680633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.652051ms","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-04-07T05:46:20.680671Z","caller":"traceutil/trace.go:171","msg":"trace[1001363745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"420.729986ms","start":"2026-04-07T05:46:20.259928Z","end":"2026-04-07T05:46:20.680658Z","steps":["trace[1001363745] 'agreement among raft nodes before linearized reading' (duration: 420.581954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:20.6807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:20.259895Z","time spent":"420.799083ms","remote":"127.0.0.1:45624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T05:46:20.680885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.408987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:46:20.680921Z","caller":"traceutil/trace.go:171","msg":"trace[543688425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"182.472563ms","start":"2026-04-07T05:46:20.498438Z","end":"2026-04-07T05:46:20.68091Z","steps":["trace[543688425] 'agreement among raft nodes before linearized reading' (duration: 182.401187ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:46:20.68079Z","caller":"traceutil/trace.go:171","msg":"trace[1626438239] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"627.910715ms","start":"2026-04-07T05:46:20.052833Z","end":"2026-04-07T05:46:20.680743Z","steps":["trace[1626438239] 'process raft request' (duration: 627.334557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:46:20.681791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:46:20.052823Z","time spent":"628.798269ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:47:11.184339Z","caller":"traceutil/trace.go:171","msg":"trace[125709202] linearizableReadLoop","detail":"{readStateIndex:1198; appliedIndex:1197; }","duration":"206.445487ms","start":"2026-04-07T05:47:10.977863Z","end":"2026-04-07T05:47:11.184309Z","steps":["trace[125709202] 'read index received' (duration: 206.193708ms)","trace[125709202] 'applied index is now lower than readState.Index' (duration: 250.569µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:47:11.184404Z","caller":"traceutil/trace.go:171","msg":"trace[1388485712] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"304.993376ms","start":"2026-04-07T05:47:10.879374Z","end":"2026-04-07T05:47:11.184368Z","steps":["trace[1388485712] 'process raft request' (duration: 304.777465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:47:11.184651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:47:10.879349Z","time spent":"305.179467ms","remote":"127.0.0.1:45624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:47:11.184707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.81256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T05:47:11.184653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.745277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-04-07T05:47:11.184857Z","caller":"traceutil/trace.go:171","msg":"trace[349415535] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1162; }","duration":"182.004954ms","start":"2026-04-07T05:47:11.002832Z","end":"2026-04-07T05:47:11.184837Z","steps":["trace[349415535] 'agreement among raft nodes before linearized reading' (duration: 181.695098ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:47:11.184917Z","caller":"traceutil/trace.go:171","msg":"trace[1256180520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1162; }","duration":"206.975373ms","start":"2026-04-07T05:47:10.977796Z","end":"2026-04-07T05:47:11.184772Z","steps":["trace[1256180520] 'agreement among raft nodes before linearized reading' (duration: 206.643898ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:47:57.876006Z","caller":"traceutil/trace.go:171","msg":"trace[1623382964] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"403.231379ms","start":"2026-04-07T05:47:57.472747Z","end":"2026-04-07T05:47:57.875978Z","steps":["trace[1623382964] 'process raft request' (duration: 329.806927ms)","trace[1623382964] 'compare' (duration: 73.097546ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:47:57.876154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:47:57.472725Z","time spent":"403.357151ms","remote":"127.0.0.1:45624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:47:57.927437Z","caller":"traceutil/trace.go:171","msg":"trace[2126608632] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"453.579516ms","start":"2026-04-07T05:47:57.473833Z","end":"2026-04-07T05:47:57.927412Z","steps":["trace[2126608632] 'process raft request' (duration: 453.374272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:47:57.927615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:47:57.473816Z","time spent":"453.700348ms","remote":"127.0.0.1:45624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:47:57.927632Z","caller":"traceutil/trace.go:171","msg":"trace[1613085999] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1893; }","duration":"429.987022ms","start":"2026-04-07T05:47:57.497605Z","end":"2026-04-07T05:47:57.927592Z","steps":["trace[1613085999] 'read index received' (duration: 304.959039ms)","trace[1613085999] 'applied index is now lower than readState.Index' (duration: 125.026813ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:47:57.927833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.165045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:47:57.927878Z","caller":"traceutil/trace.go:171","msg":"trace[1440486306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1822; }","duration":"430.240376ms","start":"2026-04-07T05:47:57.497615Z","end":"2026-04-07T05:47:57.927855Z","steps":["trace[1440486306] 'agreement among raft nodes before linearized reading' (duration: 430.170546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:47:57.927909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:47:57.497601Z","time spent":"430.300798ms","remote":"127.0.0.1:45424","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-04-07T05:47:57.927921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.292048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T05:47:57.927996Z","caller":"traceutil/trace.go:171","msg":"trace[1564066468] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"263.230179ms","start":"2026-04-07T05:47:57.664754Z","end":"2026-04-07T05:47:57.927984Z","steps":["trace[1564066468] 'process raft request' (duration: 262.604009ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:47:57.928Z","caller":"traceutil/trace.go:171","msg":"trace[1646384822] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1822; }","duration":"430.399718ms","start":"2026-04-07T05:47:57.497578Z","end":"2026-04-07T05:47:57.927978Z","steps":["trace[1646384822] 'agreement among raft nodes before linearized reading' (duration: 430.172724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:47:57.928055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:47:57.497561Z","time spent":"430.48254ms","remote":"127.0.0.1:45624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T05:47:57.928278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.947293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-07T05:47:57.928394Z","caller":"traceutil/trace.go:171","msg":"trace[1118162907] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1822; }","duration":"194.119565ms","start":"2026-04-07T05:47:57.734256Z","end":"2026-04-07T05:47:57.928375Z","steps":["trace[1118162907] 'agreement among raft nodes before linearized reading' (duration: 193.819239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:47:57.928793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.81311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T05:47:57.92899Z","caller":"traceutil/trace.go:171","msg":"trace[1240367680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1822; }","duration":"251.038715ms","start":"2026-04-07T05:47:57.677881Z","end":"2026-04-07T05:47:57.92892Z","steps":["trace[1240367680] 'agreement among raft nodes before linearized reading' (duration: 250.380653ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:47:58.097143Z","caller":"traceutil/trace.go:171","msg":"trace[1084589208] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"162.639181ms","start":"2026-04-07T05:47:57.934477Z","end":"2026-04-07T05:47:58.097116Z","steps":["trace[1084589208] 'process raft request' (duration: 82.807786ms)","trace[1084589208] 'compare' (duration: 79.568679ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:47:58.097388Z","caller":"traceutil/trace.go:171","msg":"trace[306295544] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1897; }","duration":"134.84693ms","start":"2026-04-07T05:47:57.962525Z","end":"2026-04-07T05:47:58.097372Z","steps":["trace[306295544] 'read index received' (duration: 54.678699ms)","trace[306295544] 'applied index is now lower than readState.Index' (duration: 80.166901ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:47:58.097603Z","caller":"traceutil/trace.go:171","msg":"trace[1542762093] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"162.373086ms","start":"2026-04-07T05:47:57.935213Z","end":"2026-04-07T05:47:58.097586Z","steps":["trace[1542762093] 'process raft request' (duration: 161.853956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:47:58.097794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.677832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dd40003b-99da-452a-bc6d-93792b54c03f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-07T05:47:58.097951Z","caller":"traceutil/trace.go:171","msg":"trace[567276144] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dd40003b-99da-452a-bc6d-93792b54c03f; range_end:; response_count:1; response_revision:1825; }","duration":"131.866966ms","start":"2026-04-07T05:47:57.966065Z","end":"2026-04-07T05:47:58.097932Z","steps":["trace[567276144] 'agreement among raft nodes before linearized reading' (duration: 131.57273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:47:58.098043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.505442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T05:47:58.098097Z","caller":"traceutil/trace.go:171","msg":"trace[603534437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1825; }","duration":"135.616575ms","start":"2026-04-07T05:47:57.962469Z","end":"2026-04-07T05:47:58.098086Z","steps":["trace[603534437] 'agreement among raft nodes before linearized reading' (duration: 135.456631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:47:58.474244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.713093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:47:58.474392Z","caller":"traceutil/trace.go:171","msg":"trace[1729514463] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"369.389419ms","start":"2026-04-07T05:47:58.104976Z","end":"2026-04-07T05:47:58.474366Z","steps":["trace[1729514463] 'process raft request' (duration: 181.495162ms)","trace[1729514463] 'compare' (duration: 187.141872ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:47:58.474519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:47:58.104955Z","time spent":"369.494201ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:48:32.255517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.323226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:48:32.255853Z","caller":"traceutil/trace.go:171","msg":"trace[1015941823] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"169.91841ms","start":"2026-04-07T05:48:32.085887Z","end":"2026-04-07T05:48:32.255805Z","steps":["trace[1015941823] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:536; } (duration: 164.173439ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:48:32.762575Z","caller":"traceutil/trace.go:171","msg":"trace[40567980] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"203.144101ms","start":"2026-04-07T05:48:32.55941Z","end":"2026-04-07T05:48:32.762554Z","steps":["trace[40567980] 'process raft request' (duration: 202.600937ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:49:28.762448Z","caller":"traceutil/trace.go:171","msg":"trace[1314116302] transaction","detail":"{read_only:false; response_revision:2419; number_of_response:1; }","duration":"195.474689ms","start":"2026-04-07T05:49:28.566945Z","end":"2026-04-07T05:49:28.76242Z","steps":["trace[1314116302] 'process raft request' (duration: 192.721664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:49:36.980062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.137362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T05:49:36.980142Z","caller":"traceutil/trace.go:171","msg":"trace[1798659887] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:2517; }","duration":"209.2629ms","start":"2026-04-07T05:49:36.77086Z","end":"2026-04-07T05:49:36.980123Z","steps":["trace[1798659887] 'range keys from in-memory index tree' (duration: 208.935819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:49:36.980398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.349501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T05:49:36.980463Z","caller":"traceutil/trace.go:171","msg":"trace[614098005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2517; }","duration":"177.464149ms","start":"2026-04-07T05:49:36.802982Z","end":"2026-04-07T05:49:36.980446Z","steps":["trace[614098005] 'range keys from in-memory index tree' (duration: 177.238634ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:49:37.131875Z","caller":"traceutil/trace.go:171","msg":"trace[404917769] transaction","detail":"{read_only:false; response_revision:2518; number_of_response:1; }","duration":"146.202166ms","start":"2026-04-07T05:49:36.985649Z","end":"2026-04-07T05:49:37.131851Z","steps":["trace[404917769] 'process raft request' (duration: 135.256387ms)","trace[404917769] 'compare' (duration: 10.775758ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:50:09.982533Z","caller":"traceutil/trace.go:171","msg":"trace[647082823] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"182.331503ms","start":"2026-04-07T05:50:09.800165Z","end":"2026-04-07T05:50:09.982497Z","steps":["trace[647082823] 'process raft request' (duration: 182.121199ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:50:09.98266Z","caller":"traceutil/trace.go:171","msg":"trace[1063062774] linearizableReadLoop","detail":"{readStateIndex:2802; appliedIndex:2801; }","duration":"143.827608ms","start":"2026-04-07T05:50:09.838789Z","end":"2026-04-07T05:50:09.982617Z","steps":["trace[1063062774] 'read index received' (duration: 143.507685ms)","trace[1063062774] 'applied index is now lower than readState.Index' (duration: 312.592µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:50:09.982865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.035902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:50:09.982956Z","caller":"traceutil/trace.go:171","msg":"trace[287852787] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2698; }","duration":"144.192073ms","start":"2026-04-07T05:50:09.838748Z","end":"2026-04-07T05:50:09.98294Z","steps":["trace[287852787] 'agreement among raft nodes before linearized reading' (duration: 143.990638ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:50:10.09497Z","caller":"traceutil/trace.go:171","msg":"trace[803024813] linearizableReadLoop","detail":"{readStateIndex:2804; appliedIndex:2802; }","duration":"108.991571ms","start":"2026-04-07T05:50:09.985956Z","end":"2026-04-07T05:50:10.094948Z","steps":["trace[803024813] 'read index received' (duration: 89.419297ms)","trace[803024813] 'applied index is now lower than readState.Index' (duration: 19.571373ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:50:10.095013Z","caller":"traceutil/trace.go:171","msg":"trace[839462113] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"221.290453ms","start":"2026-04-07T05:50:09.873683Z","end":"2026-04-07T05:50:10.094974Z","steps":["trace[839462113] 'process raft request' (duration: 201.774715ms)","trace[839462113] 'compare' (duration: 19.241349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:50:10.095132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.150471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:50:10.095187Z","caller":"traceutil/trace.go:171","msg":"trace[1923385161] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2700; }","duration":"109.239477ms","start":"2026-04-07T05:50:09.985932Z","end":"2026-04-07T05:50:10.095171Z","steps":["trace[1923385161] 'agreement among raft nodes before linearized reading' (duration: 109.13533ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:50:10.095412Z","caller":"traceutil/trace.go:171","msg":"trace[878719655] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"165.030443ms","start":"2026-04-07T05:50:09.930365Z","end":"2026-04-07T05:50:10.095396Z","steps":["trace[878719655] 'process raft request' (duration: 164.526937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:50:10.713591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.679695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:50:10.713702Z","caller":"traceutil/trace.go:171","msg":"trace[423241893] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2700; }","duration":"325.838216ms","start":"2026-04-07T05:50:10.387834Z","end":"2026-04-07T05:50:10.713672Z","steps":["trace[423241893] 'count revisions from in-memory index tree' (duration: 325.59862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:50:10.713775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:50:10.387813Z","time spent":"325.946803ms","remote":"127.0.0.1:36908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:50:10.71391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.424822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a3fd1f9779d938\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-07T05:50:10.713972Z","caller":"traceutil/trace.go:171","msg":"trace[1746719989] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a3fd1f9779d938; range_end:; response_count:1; response_revision:2700; }","duration":"469.526449ms","start":"2026-04-07T05:50:10.244434Z","end":"2026-04-07T05:50:10.71396Z","steps":["trace[1746719989] 'range keys from in-memory index tree' (duration: 469.183174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:50:10.713981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.590896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-07T05:50:10.714006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:50:10.244415Z","time spent":"469.581753ms","remote":"127.0.0.1:45494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":805,"request content":"key:\"/registry/events/auth-system/keycloak-0.18a3fd1f9779d938\" "} {"level":"info","ts":"2026-04-07T05:50:10.714021Z","caller":"traceutil/trace.go:171","msg":"trace[749794190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2700; }","duration":"142.666992ms","start":"2026-04-07T05:50:10.571342Z","end":"2026-04-07T05:50:10.714009Z","steps":["trace[749794190] 'range keys from in-memory index tree' (duration: 142.411933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:50:10.714334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.874106ms","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-04-07T05:50:10.714365Z","caller":"traceutil/trace.go:171","msg":"trace[624070177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2700; }","duration":"142.918719ms","start":"2026-04-07T05:50:10.571437Z","end":"2026-04-07T05:50:10.714356Z","steps":["trace[624070177] 'range keys from in-memory index tree' (duration: 142.179406ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:50:10.87625Z","caller":"traceutil/trace.go:171","msg":"trace[970305893] linearizableReadLoop","detail":"{readStateIndex:2807; appliedIndex:2804; }","duration":"113.194929ms","start":"2026-04-07T05:50:10.763032Z","end":"2026-04-07T05:50:10.876227Z","steps":["trace[970305893] 'read index received' (duration: 9.749579ms)","trace[970305893] 'applied index is now lower than readState.Index' (duration: 103.44418ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:50:10.876543Z","caller":"traceutil/trace.go:171","msg":"trace[1118073230] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"157.934571ms","start":"2026-04-07T05:50:10.718589Z","end":"2026-04-07T05:50:10.876523Z","steps":["trace[1118073230] 'process raft request' (duration: 157.590627ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:50:10.876455Z","caller":"traceutil/trace.go:171","msg":"trace[89831230] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"158.087391ms","start":"2026-04-07T05:50:10.718344Z","end":"2026-04-07T05:50:10.876432Z","steps":["trace[89831230] 'process raft request' (duration: 157.326608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:50:10.876746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.691194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T05:50:10.876811Z","caller":"traceutil/trace.go:171","msg":"trace[631927776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2703; }","duration":"113.79742ms","start":"2026-04-07T05:50:10.762995Z","end":"2026-04-07T05:50:10.876792Z","steps":["trace[631927776] 'agreement among raft nodes before linearized reading' (duration: 113.561424ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:50:16.188047Z","caller":"traceutil/trace.go:171","msg":"trace[1521371176] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"188.239547ms","start":"2026-04-07T05:50:15.999787Z","end":"2026-04-07T05:50:16.188026Z","steps":["trace[1521371176] 'process raft request' (duration: 188.095276ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:00.123702Z","caller":"traceutil/trace.go:171","msg":"trace[1001637652] transaction","detail":"{read_only:false; response_revision:2961; number_of_response:1; }","duration":"112.712575ms","start":"2026-04-07T05:51:00.010959Z","end":"2026-04-07T05:51:00.123672Z","steps":["trace[1001637652] 'process raft request' (duration: 112.51439ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:00.151425Z","caller":"traceutil/trace.go:171","msg":"trace[1268306585] transaction","detail":"{read_only:false; response_revision:2962; number_of_response:1; }","duration":"139.247526ms","start":"2026-04-07T05:51:00.012152Z","end":"2026-04-07T05:51:00.1514Z","steps":["trace[1268306585] 'process raft request' (duration: 139.113536ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:51:30.785472Z","caller":"traceutil/trace.go:171","msg":"trace[387209485] transaction","detail":"{read_only:false; response_revision:3124; number_of_response:1; }","duration":"122.183336ms","start":"2026-04-07T05:51:30.663258Z","end":"2026-04-07T05:51:30.785441Z","steps":["trace[387209485] 'process raft request' (duration: 62.166507ms)","trace[387209485] 'compare' (duration: 59.845923ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:51:31.484149Z","caller":"traceutil/trace.go:171","msg":"trace[803110459] linearizableReadLoop","detail":"{readStateIndex:3247; appliedIndex:3246; }","duration":"152.824368ms","start":"2026-04-07T05:51:31.331297Z","end":"2026-04-07T05:51:31.484121Z","steps":["trace[803110459] 'read index received' (duration: 85.352586ms)","trace[803110459] 'applied index is now lower than readState.Index' (duration: 67.470682ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:51:31.484199Z","caller":"traceutil/trace.go:171","msg":"trace[476027857] transaction","detail":"{read_only:false; response_revision:3126; number_of_response:1; }","duration":"155.432444ms","start":"2026-04-07T05:51:31.328731Z","end":"2026-04-07T05:51:31.484163Z","steps":["trace[476027857] 'process raft request' (duration: 87.860926ms)","trace[476027857] 'compare' (duration: 67.409113ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:51:31.484679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.346395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T05:51:31.484756Z","caller":"traceutil/trace.go:171","msg":"trace[656671588] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3126; }","duration":"153.455232ms","start":"2026-04-07T05:51:31.331278Z","end":"2026-04-07T05:51:31.484733Z","steps":["trace[656671588] 'agreement among raft nodes before linearized reading' (duration: 153.030882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:51:31.484852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.305905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T05:51:31.484929Z","caller":"traceutil/trace.go:171","msg":"trace[1246204471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3126; }","duration":"153.464772ms","start":"2026-04-07T05:51:31.331449Z","end":"2026-04-07T05:51:31.484914Z","steps":["trace[1246204471] 'agreement among raft nodes before linearized reading' (duration: 152.884286ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:52:46.183174Z","caller":"traceutil/trace.go:171","msg":"trace[1387379305] transaction","detail":"{read_only:false; response_revision:3614; number_of_response:1; }","duration":"107.72174ms","start":"2026-04-07T05:52:46.075418Z","end":"2026-04-07T05:52:46.183139Z","steps":["trace[1387379305] 'process raft request' (duration: 63.317778ms)","trace[1387379305] 'compare' (duration: 43.876574ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:53:51.565974Z","caller":"traceutil/trace.go:171","msg":"trace[754191934] transaction","detail":"{read_only:false; response_revision:4394; number_of_response:1; }","duration":"211.568177ms","start":"2026-04-07T05:53:51.354377Z","end":"2026-04-07T05:53:51.565945Z","steps":["trace[754191934] 'process raft request' (duration: 208.495428ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:51.566307Z","caller":"traceutil/trace.go:171","msg":"trace[984167631] linearizableReadLoop","detail":"{readStateIndex:4567; appliedIndex:4565; }","duration":"149.192913ms","start":"2026-04-07T05:53:51.417094Z","end":"2026-04-07T05:53:51.566287Z","steps":["trace[984167631] 'read index received' (duration: 145.795522ms)","trace[984167631] 'applied index is now lower than readState.Index' (duration: 3.396331ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:53:51.566445Z","caller":"traceutil/trace.go:171","msg":"trace[1368718583] transaction","detail":"{read_only:false; response_revision:4395; number_of_response:1; }","duration":"209.922318ms","start":"2026-04-07T05:53:51.356511Z","end":"2026-04-07T05:53:51.566433Z","steps":["trace[1368718583] 'process raft request' (duration: 209.383159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:51.566778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.64235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:53:51.567069Z","caller":"traceutil/trace.go:171","msg":"trace[450569230] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:4395; }","duration":"149.966312ms","start":"2026-04-07T05:53:51.417036Z","end":"2026-04-07T05:53:51.567002Z","steps":["trace[450569230] 'agreement among raft nodes before linearized reading' (duration: 149.655871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:51.567358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.645121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T05:53:51.567474Z","caller":"traceutil/trace.go:171","msg":"trace[378070358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4395; }","duration":"142.772046ms","start":"2026-04-07T05:53:51.424683Z","end":"2026-04-07T05:53:51.567455Z","steps":["trace[378070358] 'agreement among raft nodes before linearized reading' (duration: 142.07319ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:52.497969Z","caller":"traceutil/trace.go:171","msg":"trace[1058445780] linearizableReadLoop","detail":"{readStateIndex:4571; appliedIndex:4570; }","duration":"212.403007ms","start":"2026-04-07T05:53:52.285539Z","end":"2026-04-07T05:53:52.497942Z","steps":["trace[1058445780] 'read index received' (duration: 212.181009ms)","trace[1058445780] 'applied index is now lower than readState.Index' (duration: 220.848µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:53:52.498032Z","caller":"traceutil/trace.go:171","msg":"trace[1624591465] transaction","detail":"{read_only:false; response_revision:4398; number_of_response:1; }","duration":"267.488224ms","start":"2026-04-07T05:53:52.230502Z","end":"2026-04-07T05:53:52.49799Z","steps":["trace[1624591465] 'process raft request' (duration: 267.276206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:52.498274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.667626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T05:53:52.498323Z","caller":"traceutil/trace.go:171","msg":"trace[1464080957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4398; }","duration":"212.811161ms","start":"2026-04-07T05:53:52.285498Z","end":"2026-04-07T05:53:52.498309Z","steps":["trace[1464080957] 'agreement among raft nodes before linearized reading' (duration: 212.581053ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:52.675678Z","caller":"traceutil/trace.go:171","msg":"trace[2016800346] transaction","detail":"{read_only:false; response_revision:4400; number_of_response:1; }","duration":"172.129007ms","start":"2026-04-07T05:53:52.503523Z","end":"2026-04-07T05:53:52.675652Z","steps":["trace[2016800346] 'process raft request' (duration: 99.718781ms)","trace[2016800346] 'compare' (duration: 71.975031ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:53:56.026176Z","caller":"traceutil/trace.go:171","msg":"trace[1640237852] transaction","detail":"{read_only:false; response_revision:4482; number_of_response:1; }","duration":"122.522085ms","start":"2026-04-07T05:53:55.90362Z","end":"2026-04-07T05:53:56.026142Z","steps":["trace[1640237852] 'process raft request' (duration: 122.216765ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:57.25143Z","caller":"traceutil/trace.go:171","msg":"trace[1592082992] transaction","detail":"{read_only:false; response_revision:4521; number_of_response:1; }","duration":"117.139017ms","start":"2026-04-07T05:53:57.134253Z","end":"2026-04-07T05:53:57.251392Z","steps":["trace[1592082992] 'process raft request' (duration: 40.544174ms)","trace[1592082992] 'compare' (duration: 76.388026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:54:05.000441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.857577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:54:05.000772Z","caller":"traceutil/trace.go:171","msg":"trace[1084707141] linearizableReadLoop","detail":"{readStateIndex:4873; appliedIndex:4872; }","duration":"203.108585ms","start":"2026-04-07T05:54:04.797643Z","end":"2026-04-07T05:54:05.000751Z","steps":["trace[1084707141] 'read index received' (duration: 43.790281ms)","trace[1084707141] 'applied index is now lower than readState.Index' (duration: 159.316364ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:05.000943Z","caller":"traceutil/trace.go:171","msg":"trace[651739316] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"203.440478ms","start":"2026-04-07T05:54:04.797459Z","end":"2026-04-07T05:54:05.000899Z","steps":["trace[651739316] 'process raft request' (duration: 44.03931ms)","trace[651739316] 'compare' (duration: 158.70258ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:54:05.001255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.558468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-04-07T05:54:05.001391Z","caller":"traceutil/trace.go:171","msg":"trace[769329981] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4694; }","duration":"194.687522ms","start":"2026-04-07T05:54:04.80664Z","end":"2026-04-07T05:54:05.001327Z","steps":["trace[769329981] 'agreement among raft nodes before linearized reading' (duration: 194.352289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:05.001549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.77205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-07T05:54:05.001598Z","caller":"traceutil/trace.go:171","msg":"trace[1228153433] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4694; }","duration":"160.914346ms","start":"2026-04-07T05:54:04.840666Z","end":"2026-04-07T05:54:05.00158Z","steps":["trace[1228153433] 'agreement among raft nodes before linearized reading' (duration: 160.713728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:05.001969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.698408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-799f6db745-dfdnw\" ","response":"range_response_count:1 size:11257"} {"level":"info","ts":"2026-04-07T05:54:05.002084Z","caller":"traceutil/trace.go:171","msg":"trace[1663599186] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-799f6db745-dfdnw; range_end:; response_count:1; response_revision:4694; }","duration":"204.453598ms","start":"2026-04-07T05:54:04.797609Z","end":"2026-04-07T05:54:05.002062Z","steps":["trace[1663599186] 'agreement among raft nodes before linearized reading' (duration: 203.366055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:05.00148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.621768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T05:54:05.002547Z","caller":"traceutil/trace.go:171","msg":"trace[1078616140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4694; }","duration":"116.760372ms","start":"2026-04-07T05:54:04.885763Z","end":"2026-04-07T05:54:05.002523Z","steps":["trace[1078616140] 'agreement among raft nodes before linearized reading' (duration: 115.343388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:05.426833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.920481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:54:05.427094Z","caller":"traceutil/trace.go:171","msg":"trace[1521261505] transaction","detail":"{read_only:false; response_revision:4696; number_of_response:1; }","duration":"414.087361ms","start":"2026-04-07T05:54:05.01298Z","end":"2026-04-07T05:54:05.427068Z","steps":["trace[1521261505] 'process raft request' (duration: 245.810286ms)","trace[1521261505] 'compare' (duration: 167.786105ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:05.427166Z","caller":"traceutil/trace.go:171","msg":"trace[608299652] transaction","detail":"{read_only:false; response_revision:4697; number_of_response:1; }","duration":"400.964545ms","start":"2026-04-07T05:54:05.026175Z","end":"2026-04-07T05:54:05.42714Z","steps":["trace[608299652] 'process raft request' (duration: 400.760417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:05.427221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:05.026154Z","time spent":"401.040047ms","remote":"127.0.0.1:45494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:54:05.427247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:05.012954Z","time spent":"414.196404ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11833,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:54:05.427111Z","caller":"traceutil/trace.go:171","msg":"trace[986727719] transaction","detail":"{read_only:false; response_revision:4698; number_of_response:1; }","duration":"140.227351ms","start":"2026-04-07T05:54:05.286862Z","end":"2026-04-07T05:54:05.427089Z","steps":["trace[986727719] 'process raft request' (duration: 140.165149ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:05.427412Z","caller":"traceutil/trace.go:171","msg":"trace[2118218803] linearizableReadLoop","detail":"{readStateIndex:4876; appliedIndex:4873; }","duration":"400.924133ms","start":"2026-04-07T05:54:05.026478Z","end":"2026-04-07T05:54:05.427402Z","steps":["trace[2118218803] 'read index received' (duration: 26.136149ms)","trace[2118218803] 'applied index is now lower than readState.Index' (duration: 374.786884ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:54:05.42774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.257035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-07T05:54:05.427775Z","caller":"traceutil/trace.go:171","msg":"trace[1654080813] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4698; }","duration":"401.313097ms","start":"2026-04-07T05:54:05.02645Z","end":"2026-04-07T05:54:05.427763Z","steps":["trace[1654080813] 'agreement among raft nodes before linearized reading' (duration: 401.203023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:05.427759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.948983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d64ef10b-708d-4b39-855d-4b03047004a6\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-04-07T05:54:05.427798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:05.026442Z","time spent":"401.351849ms","remote":"127.0.0.1:45512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3530,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-04-07T05:54:05.427825Z","caller":"traceutil/trace.go:171","msg":"trace[905962671] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d64ef10b-708d-4b39-855d-4b03047004a6; range_end:; response_count:1; response_revision:4698; }","duration":"259.067448ms","start":"2026-04-07T05:54:05.168741Z","end":"2026-04-07T05:54:05.427809Z","steps":["trace[905962671] 'agreement among raft nodes before linearized reading' (duration: 258.870581ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:05.663622Z","caller":"traceutil/trace.go:171","msg":"trace[1931855453] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"231.14732ms","start":"2026-04-07T05:54:05.432437Z","end":"2026-04-07T05:54:05.663584Z","steps":["trace[1931855453] 'process raft request' (duration: 132.442272ms)","trace[1931855453] 'compare' (duration: 98.374666ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:05.677568Z","caller":"traceutil/trace.go:171","msg":"trace[1026453532] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"244.889891ms","start":"2026-04-07T05:54:05.432648Z","end":"2026-04-07T05:54:05.677538Z","steps":["trace[1026453532] 'process raft request' (duration: 244.679353ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:05.677585Z","caller":"traceutil/trace.go:171","msg":"trace[376396519] linearizableReadLoop","detail":"{readStateIndex:4880; appliedIndex:4877; }","duration":"242.376294ms","start":"2026-04-07T05:54:05.43519Z","end":"2026-04-07T05:54:05.677566Z","steps":["trace[376396519] 'read index received' (duration: 129.746427ms)","trace[376396519] 'applied index is now lower than readState.Index' (duration: 112.628967ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:05.677677Z","caller":"traceutil/trace.go:171","msg":"trace[18735108] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"230.415293ms","start":"2026-04-07T05:54:05.447244Z","end":"2026-04-07T05:54:05.67766Z","steps":["trace[18735108] 'process raft request' (duration: 230.280357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:05.677851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.645474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-07T05:54:05.677956Z","caller":"traceutil/trace.go:171","msg":"trace[1114821400] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4702; }","duration":"242.758388ms","start":"2026-04-07T05:54:05.435187Z","end":"2026-04-07T05:54:05.677945Z","steps":["trace[1114821400] 'agreement among raft nodes before linearized reading' (duration: 242.581502ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:05.677825Z","caller":"traceutil/trace.go:171","msg":"trace[597844356] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"244.147392ms","start":"2026-04-07T05:54:05.433658Z","end":"2026-04-07T05:54:05.677806Z","steps":["trace[597844356] 'process raft request' (duration: 243.811569ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:05.73976Z","caller":"traceutil/trace.go:171","msg":"trace[945855464] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"117.1296ms","start":"2026-04-07T05:54:05.622604Z","end":"2026-04-07T05:54:05.739734Z","steps":["trace[945855464] 'process raft request' (duration: 116.989745ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:05.740151Z","caller":"traceutil/trace.go:171","msg":"trace[411069010] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"114.243788ms","start":"2026-04-07T05:54:05.625854Z","end":"2026-04-07T05:54:05.740097Z","steps":["trace[411069010] 'process raft request' (duration: 113.844733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:05.740337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.545391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:54:05.740341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.388351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T05:54:05.740416Z","caller":"traceutil/trace.go:171","msg":"trace[723916783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4705; }","duration":"241.670687ms","start":"2026-04-07T05:54:05.498727Z","end":"2026-04-07T05:54:05.740397Z","steps":["trace[723916783] 'agreement among raft nodes before linearized reading' (duration: 241.560243ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:05.740441Z","caller":"traceutil/trace.go:171","msg":"trace[277428903] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:4705; }","duration":"237.534546ms","start":"2026-04-07T05:54:05.502887Z","end":"2026-04-07T05:54:05.740422Z","steps":["trace[277428903] 'agreement among raft nodes before linearized reading' (duration: 237.277007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:06.075977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.541652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:54:06.076242Z","caller":"traceutil/trace.go:171","msg":"trace[1974631702] linearizableReadLoop","detail":"{readStateIndex:4888; appliedIndex:4884; }","duration":"336.117508ms","start":"2026-04-07T05:54:05.740107Z","end":"2026-04-07T05:54:06.076224Z","steps":["trace[1974631702] 'read index received' (duration: 33.208801ms)","trace[1974631702] 'applied index is now lower than readState.Index' (duration: 302.907767ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:06.07632Z","caller":"traceutil/trace.go:171","msg":"trace[2022616751] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"386.881806ms","start":"2026-04-07T05:54:05.689428Z","end":"2026-04-07T05:54:06.07631Z","steps":["trace[2022616751] 'process raft request' (duration: 83.893537ms)","trace[2022616751] 'compare' (duration: 302.343434ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:54:06.076376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:05.689404Z","time spent":"386.940729ms","remote":"127.0.0.1:45630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:54:06.076549Z","caller":"traceutil/trace.go:171","msg":"trace[152968175] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"382.795239ms","start":"2026-04-07T05:54:05.693745Z","end":"2026-04-07T05:54:06.07654Z","steps":["trace[152968175] 'process raft request' (duration: 382.325711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:06.076597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:05.693713Z","time spent":"382.857512ms","remote":"127.0.0.1:45630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:54:06.076916Z","caller":"traceutil/trace.go:171","msg":"trace[1473332403] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"382.184376ms","start":"2026-04-07T05:54:05.69472Z","end":"2026-04-07T05:54:06.076905Z","steps":["trace[1473332403] 'process raft request' (duration: 381.405036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:06.076979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:05.6947Z","time spent":"382.249518ms","remote":"127.0.0.1:45560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":850,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:54:06.077132Z","caller":"traceutil/trace.go:171","msg":"trace[1677638706] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"382.354863ms","start":"2026-04-07T05:54:05.694749Z","end":"2026-04-07T05:54:06.077104Z","steps":["trace[1677638706] 'process raft request' (duration: 381.423026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:06.077329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.361931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1346"} {"level":"info","ts":"2026-04-07T05:54:06.077436Z","caller":"traceutil/trace.go:171","msg":"trace[1560568218] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4709; }","duration":"383.531239ms","start":"2026-04-07T05:54:05.693886Z","end":"2026-04-07T05:54:06.077417Z","steps":["trace[1560568218] 'agreement among raft nodes before linearized reading' (duration: 383.281808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:06.07748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:05.693857Z","time spent":"383.613181ms","remote":"127.0.0.1:45512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1370,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-04-07T05:54:06.079278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.526982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:54:06.079358Z","caller":"traceutil/trace.go:171","msg":"trace[1311254660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4711; }","duration":"337.677967ms","start":"2026-04-07T05:54:05.741662Z","end":"2026-04-07T05:54:06.07934Z","steps":["trace[1311254660] 'agreement among raft nodes before linearized reading' (duration: 337.537672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:06.079396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.371755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-799f6db745-dfdnw.18a3fd5adc1739bf\" ","response":"range_response_count:1 size:898"} {"level":"warn","ts":"2026-04-07T05:54:06.079402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:05.741645Z","time spent":"337.747341ms","remote":"127.0.0.1:45410","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-04-07T05:54:06.079411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.197096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-07T05:54:06.079427Z","caller":"traceutil/trace.go:171","msg":"trace[1948251997] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-799f6db745-dfdnw.18a3fd5adc1739bf; range_end:; response_count:1; response_revision:4711; }","duration":"267.437948ms","start":"2026-04-07T05:54:05.811979Z","end":"2026-04-07T05:54:06.079417Z","steps":["trace[1948251997] 'agreement among raft nodes before linearized reading' (duration: 267.352114ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:06.079465Z","caller":"traceutil/trace.go:171","msg":"trace[2068521180] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4711; }","duration":"262.267738ms","start":"2026-04-07T05:54:05.817181Z","end":"2026-04-07T05:54:06.079448Z","steps":["trace[2068521180] 'agreement among raft nodes before linearized reading' (duration: 262.095852ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:06.079658Z","caller":"traceutil/trace.go:171","msg":"trace[1780632555] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"311.429065ms","start":"2026-04-07T05:54:05.76822Z","end":"2026-04-07T05:54:06.079649Z","steps":["trace[1780632555] 'process raft request' (duration: 310.919166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:06.079682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.09153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-04-07T05:54:06.079717Z","caller":"traceutil/trace.go:171","msg":"trace[661860818] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4711; }","duration":"270.157993ms","start":"2026-04-07T05:54:05.809548Z","end":"2026-04-07T05:54:06.079706Z","steps":["trace[661860818] 'agreement among raft nodes before linearized reading' (duration: 270.032729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:06.079746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:05.768189Z","time spent":"311.487968ms","remote":"127.0.0.1:45624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:54:06.079818Z","caller":"traceutil/trace.go:171","msg":"trace[840747364] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"334.827968ms","start":"2026-04-07T05:54:05.744975Z","end":"2026-04-07T05:54:06.079803Z","steps":["trace[840747364] 'process raft request' (duration: 334.047758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:06.079889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:05.744945Z","time spent":"334.903811ms","remote":"127.0.0.1:45624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:54:06.08175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:05.69474Z","time spent":"382.456667ms","remote":"127.0.0.1:45560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1273,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:54:06.305737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.345045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:54:06.305834Z","caller":"traceutil/trace.go:171","msg":"trace[261735525] linearizableReadLoop","detail":"{readStateIndex:4893; appliedIndex:4892; }","duration":"150.458908ms","start":"2026-04-07T05:54:06.155359Z","end":"2026-04-07T05:54:06.305818Z","steps":["trace[261735525] 'read index received' (duration: 34.998618ms)","trace[261735525] 'applied index is now lower than readState.Index' (duration: 115.45914ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:06.306066Z","caller":"traceutil/trace.go:171","msg":"trace[1094704865] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"150.891515ms","start":"2026-04-07T05:54:06.155161Z","end":"2026-04-07T05:54:06.306053Z","steps":["trace[1094704865] 'process raft request' (duration: 35.125433ms)","trace[1094704865] 'compare' (duration: 115.224511ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:54:06.306322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.931337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-04-07T05:54:06.306413Z","caller":"traceutil/trace.go:171","msg":"trace[1558393954] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4714; }","duration":"151.069552ms","start":"2026-04-07T05:54:06.155327Z","end":"2026-04-07T05:54:06.306397Z","steps":["trace[1558393954] 'agreement among raft nodes before linearized reading' (duration: 150.785291ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:06.311643Z","caller":"traceutil/trace.go:171","msg":"trace[1023053910] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"156.149849ms","start":"2026-04-07T05:54:06.155482Z","end":"2026-04-07T05:54:06.311632Z","steps":["trace[1023053910] 'process raft request' (duration: 156.050465ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:06.311654Z","caller":"traceutil/trace.go:171","msg":"trace[991850438] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"156.18837ms","start":"2026-04-07T05:54:06.155438Z","end":"2026-04-07T05:54:06.311627Z","steps":["trace[991850438] 'process raft request' (duration: 155.997413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:06.311968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.717685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-04-07T05:54:06.312002Z","caller":"traceutil/trace.go:171","msg":"trace[612788456] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4717; }","duration":"153.774397ms","start":"2026-04-07T05:54:06.158219Z","end":"2026-04-07T05:54:06.311993Z","steps":["trace[612788456] 'agreement among raft nodes before linearized reading' (duration: 153.652582ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:06.311952Z","caller":"traceutil/trace.go:171","msg":"trace[69697582] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"156.356188ms","start":"2026-04-07T05:54:06.155534Z","end":"2026-04-07T05:54:06.31189Z","steps":["trace[69697582] 'process raft request' (duration: 156.037905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:18.430818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531557044518354,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T05:54:18.497441Z","caller":"traceutil/trace.go:171","msg":"trace[2075187661] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"716.7432ms","start":"2026-04-07T05:54:17.780669Z","end":"2026-04-07T05:54:18.497412Z","steps":["trace[2075187661] 'process raft request' (duration: 716.583334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:18.497587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:17.780643Z","time spent":"716.868255ms","remote":"127.0.0.1:45624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:54:18.510079Z","caller":"traceutil/trace.go:171","msg":"trace[1015795077] linearizableReadLoop","detail":"{readStateIndex:5060; appliedIndex:5057; }","duration":"580.044538ms","start":"2026-04-07T05:54:17.930021Z","end":"2026-04-07T05:54:18.510066Z","steps":["trace[1015795077] 'read index received' (duration: 567.279552ms)","trace[1015795077] 'applied index is now lower than readState.Index' (duration: 12.764185ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:18.510277Z","caller":"traceutil/trace.go:171","msg":"trace[1438284721] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"728.032315ms","start":"2026-04-07T05:54:17.78222Z","end":"2026-04-07T05:54:18.510253Z","steps":["trace[1438284721] 'process raft request' (duration: 727.735683ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:18.510292Z","caller":"traceutil/trace.go:171","msg":"trace[8607903] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"333.266886ms","start":"2026-04-07T05:54:18.177009Z","end":"2026-04-07T05:54:18.510276Z","steps":["trace[8607903] 'process raft request' (duration: 333.020386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:18.510365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.182789ms","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-04-07T05:54:18.510392Z","caller":"traceutil/trace.go:171","msg":"trace[1164972096] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4875; }","duration":"488.256401ms","start":"2026-04-07T05:54:18.022129Z","end":"2026-04-07T05:54:18.510385Z","steps":["trace[1164972096] 'agreement among raft nodes before linearized reading' (duration: 488.106465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:18.510409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:18.022108Z","time spent":"488.296932ms","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-07T05:54:18.510408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:17.782187Z","time spent":"728.14314ms","remote":"127.0.0.1:45624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:54:18.510472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.446025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-04-07T05:54:18.510526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.762972ms","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-04-07T05:54:18.510545Z","caller":"traceutil/trace.go:171","msg":"trace[1707347583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4875; }","duration":"134.807243ms","start":"2026-04-07T05:54:18.375733Z","end":"2026-04-07T05:54:18.51054Z","steps":["trace[1707347583] 'agreement among raft nodes before linearized reading' (duration: 134.76032ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:18.510537Z","caller":"traceutil/trace.go:171","msg":"trace[2062201878] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4875; }","duration":"580.58635ms","start":"2026-04-07T05:54:17.929941Z","end":"2026-04-07T05:54:18.510527Z","steps":["trace[2062201878] 'agreement among raft nodes before linearized reading' (duration: 580.270537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:18.510604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.293912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T05:54:18.510618Z","caller":"traceutil/trace.go:171","msg":"trace[1945032212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4875; }","duration":"337.344856ms","start":"2026-04-07T05:54:18.17327Z","end":"2026-04-07T05:54:18.510615Z","steps":["trace[1945032212] 'agreement among raft nodes before linearized reading' (duration: 337.305624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:18.510637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:18.173248Z","time spent":"337.379887ms","remote":"127.0.0.1:45624","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":"warn","ts":"2026-04-07T05:54:18.510611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:17.92991Z","time spent":"580.692044ms","remote":"127.0.0.1:45566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10416,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-07T05:54:18.510546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:18.176993Z","time spent":"333.33967ms","remote":"127.0.0.1:45494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":806,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:54:18.698879Z","caller":"traceutil/trace.go:171","msg":"trace[73855035] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"183.769459ms","start":"2026-04-07T05:54:18.515084Z","end":"2026-04-07T05:54:18.698853Z","steps":["trace[73855035] 'process raft request' (duration: 183.521009ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:18.699008Z","caller":"traceutil/trace.go:171","msg":"trace[162128842] linearizableReadLoop","detail":"{readStateIndex:5062; appliedIndex:5061; }","duration":"182.527228ms","start":"2026-04-07T05:54:18.516459Z","end":"2026-04-07T05:54:18.698987Z","steps":["trace[162128842] 'read index received' (duration: 6.023018ms)","trace[162128842] 'applied index is now lower than readState.Index' (duration: 176.50232ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:18.699101Z","caller":"traceutil/trace.go:171","msg":"trace[20028840] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"177.795433ms","start":"2026-04-07T05:54:18.521295Z","end":"2026-04-07T05:54:18.69909Z","steps":["trace[20028840] 'process raft request' (duration: 177.48277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:18.699352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.857171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-07T05:54:18.699396Z","caller":"traceutil/trace.go:171","msg":"trace[1513132691] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4878; }","duration":"182.938715ms","start":"2026-04-07T05:54:18.516443Z","end":"2026-04-07T05:54:18.699381Z","steps":["trace[1513132691] 'agreement among raft nodes before linearized reading' (duration: 182.757418ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:19.288375Z","caller":"traceutil/trace.go:171","msg":"trace[1170306451] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"148.740485ms","start":"2026-04-07T05:54:19.139599Z","end":"2026-04-07T05:54:19.28834Z","steps":["trace[1170306451] 'process raft request' (duration: 93.124784ms)","trace[1170306451] 'compare' (duration: 55.428753ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:54:19.288657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.819569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T05:54:19.288906Z","caller":"traceutil/trace.go:171","msg":"trace[1771357413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4891; }","duration":"171.420022ms","start":"2026-04-07T05:54:19.117367Z","end":"2026-04-07T05:54:19.288787Z","steps":["trace[1771357413] 'range keys from in-memory index tree' (duration: 170.63177ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:20.070048Z","caller":"traceutil/trace.go:171","msg":"trace[599054763] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"104.304938ms","start":"2026-04-07T05:54:19.965712Z","end":"2026-04-07T05:54:20.070017Z","steps":["trace[599054763] 'process raft request' (duration: 104.014107ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:20.262477Z","caller":"traceutil/trace.go:171","msg":"trace[124475081] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"177.77862ms","start":"2026-04-07T05:54:20.084675Z","end":"2026-04-07T05:54:20.262453Z","steps":["trace[124475081] 'process raft request' (duration: 177.646695ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:17.341048Z","caller":"traceutil/trace.go:171","msg":"trace[1750066047] transaction","detail":"{read_only:false; response_revision:5356; number_of_response:1; }","duration":"127.59235ms","start":"2026-04-07T05:55:17.213426Z","end":"2026-04-07T05:55:17.341019Z","steps":["trace[1750066047] 'process raft request' (duration: 121.576298ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:31.537814Z","caller":"traceutil/trace.go:171","msg":"trace[2042030781] linearizableReadLoop","detail":"{readStateIndex:5679; appliedIndex:5678; }","duration":"136.65734ms","start":"2026-04-07T05:55:31.401122Z","end":"2026-04-07T05:55:31.53778Z","steps":["trace[2042030781] 'read index received' (duration: 136.562405ms)","trace[2042030781] 'applied index is now lower than readState.Index' (duration: 94.285µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:55:31.537958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.832529ms","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-04-07T05:55:31.537981Z","caller":"traceutil/trace.go:171","msg":"trace[549712057] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5478; }","duration":"136.906105ms","start":"2026-04-07T05:55:31.401067Z","end":"2026-04-07T05:55:31.537973Z","steps":["trace[549712057] 'agreement among raft nodes before linearized reading' (duration: 136.801399ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:31.537954Z","caller":"traceutil/trace.go:171","msg":"trace[1213636747] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"250.719392ms","start":"2026-04-07T05:55:31.287196Z","end":"2026-04-07T05:55:31.537915Z","steps":["trace[1213636747] 'process raft request' (duration: 250.416976ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:43.831196Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2876} {"level":"info","ts":"2026-04-07T05:55:43.901951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2876,"took":"69.554934ms","hash":2218441935,"current-db-size-bytes":52805632,"current-db-size":"53 MB","current-db-size-in-use-bytes":29036544,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T05:55:43.902011Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2218441935,"revision":2876,"compact-revision":-1} {"level":"info","ts":"2026-04-07T05:58:00.058789Z","caller":"traceutil/trace.go:171","msg":"trace[1247604611] transaction","detail":"{read_only:false; response_revision:6487; number_of_response:1; }","duration":"119.009115ms","start":"2026-04-07T05:57:59.939741Z","end":"2026-04-07T05:58:00.05875Z","steps":["trace[1247604611] 'process raft request' (duration: 55.302239ms)","trace[1247604611] 'compare' (duration: 63.566497ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T06:00:43.893255Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5552} {"level":"info","ts":"2026-04-07T06:00:44.08068Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5552,"took":"185.752666ms","hash":3612024742,"current-db-size-bytes":52805632,"current-db-size":"53 MB","current-db-size-in-use-bytes":17948672,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-07T06:00:44.080729Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3612024742,"revision":5552,"compact-revision":2876}