{"level":"warn","ts":"2026-05-11T20:59:17.417345Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T20:59:17.417484Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T20:59:17.417605Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T20:59:17.417627Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-05-11T20:59:17.417667Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T20:59:17.418997Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-05-11T20:59:17.419154Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T20:59:17.422971Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.458121ms"} {"level":"info","ts":"2026-05-11T20:59:17.431985Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-05-11T20:59:17.432087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T20:59:17.432113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-05-11T20:59:17.432123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T20:59:17.432132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-05-11T20:59:17.432172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-05-11T20:59:17.466436Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T20:59:17.559861Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T20:59:17.560901Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-11T20:59:17.563874Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T20:59:17.567342Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T20:59:17.567428Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T20:59:17.567478Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T20:59:17.567635Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T20:59:17.568869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-05-11T20:59:17.569076Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-05-11T20:59:17.576876Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T20:59:17.577028Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-05-11T20:59:17.577068Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-05-11T20:59:17.577141Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T20:59:17.577175Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T20:59:18.033802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T20:59:18.03389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T20:59:18.033917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-05-11T20:59:18.033941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-05-11T20:59:18.033978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-05-11T20:59:18.034003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-05-11T20:59:18.034019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-05-11T20:59:18.035149Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T20:59:18.035207Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T20:59:18.035371Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T20:59:18.035574Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T20:59:18.0356Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T20:59:18.035643Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T20:59:18.036845Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T20:59:18.037033Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T20:59:18.03708Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T20:59:18.037734Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T20:59:18.038311Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-05-11T20:59:23.052984Z","caller":"traceutil/trace.go:171","msg":"trace[145921505] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"121.38963ms","start":"2026-05-11T20:59:22.931569Z","end":"2026-05-11T20:59:23.052959Z","steps":["trace[145921505] 'process raft request' (duration: 121.254508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T20:59:23.296054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.853374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T20:59:23.296181Z","caller":"traceutil/trace.go:171","msg":"trace[1976965472] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:264; }","duration":"239.18868ms","start":"2026-05-11T20:59:23.056977Z","end":"2026-05-11T20:59:23.296166Z","steps":["trace[1976965472] 'read index received' (duration: 99.740877ms)","trace[1976965472] 'applied index is now lower than readState.Index' (duration: 139.446653ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T20:59:23.296258Z","caller":"traceutil/trace.go:171","msg":"trace[1174666382] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"334.425288ms","start":"2026-05-11T20:59:22.961797Z","end":"2026-05-11T20:59:23.296222Z","steps":["trace[1174666382] 'process raft request' (duration: 194.872403ms)","trace[1174666382] 'compare' (duration: 138.718172ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T20:59:23.296309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.281112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T20:59:23.296389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T20:59:22.96177Z","time spent":"334.56169ms","remote":"127.0.0.1:38378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":170,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T20:59:23.296454Z","caller":"traceutil/trace.go:171","msg":"trace[918338526] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:256; }","duration":"239.503476ms","start":"2026-05-11T20:59:23.056937Z","end":"2026-05-11T20:59:23.29644Z","steps":["trace[918338526] 'agreement among raft nodes before linearized reading' (duration: 239.278202ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T20:59:23.439757Z","caller":"traceutil/trace.go:171","msg":"trace[900781949] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"139.867859ms","start":"2026-05-11T20:59:23.29986Z","end":"2026-05-11T20:59:23.439728Z","steps":["trace[900781949] 'process raft request' (duration: 139.717517ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T20:59:23.461925Z","caller":"traceutil/trace.go:171","msg":"trace[844068807] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"160.227522ms","start":"2026-05-11T20:59:23.301666Z","end":"2026-05-11T20:59:23.461893Z","steps":["trace[844068807] 'process raft request' (duration: 160.11349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T20:59:54.336285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.305747ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T20:59:54.336448Z","caller":"traceutil/trace.go:171","msg":"trace[421914574] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:577; }","duration":"134.496279ms","start":"2026-05-11T20:59:54.201928Z","end":"2026-05-11T20:59:54.336424Z","steps":["trace[421914574] 'range keys from in-memory index tree' (duration: 134.285557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T20:59:54.337239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.635448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T20:59:54.337351Z","caller":"traceutil/trace.go:171","msg":"trace[1396175752] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"614.512266ms","start":"2026-05-11T20:59:53.722801Z","end":"2026-05-11T20:59:54.337313Z","steps":["trace[1396175752] 'read index received' (duration: 15.422294ms)","trace[1396175752] 'applied index is now lower than readState.Index' (duration: 599.088752ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T20:59:54.33742Z","caller":"traceutil/trace.go:171","msg":"trace[423258248] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"638.156363ms","start":"2026-05-11T20:59:53.699249Z","end":"2026-05-11T20:59:54.337405Z","steps":["trace[423258248] 'process raft request' (duration: 39.097411ms)","trace[423258248] 'compare' (duration: 597.740779ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T20:59:54.337498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T20:59:53.699225Z","time spent":"638.231434ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T20:59:54.337736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.91172ms","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-05-11T20:59:54.337826Z","caller":"traceutil/trace.go:171","msg":"trace[1840042108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"615.047001ms","start":"2026-05-11T20:59:53.722759Z","end":"2026-05-11T20:59:54.337806Z","steps":["trace[1840042108] 'agreement among raft nodes before linearized reading' (duration: 614.691407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T20:59:54.337935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T20:59:53.722737Z","time spent":"615.186733ms","remote":"127.0.0.1:38426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T20:59:55.060218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.864942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T20:59:55.060409Z","caller":"traceutil/trace.go:171","msg":"trace[1097815915] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"717.169242ms","start":"2026-05-11T20:59:54.343226Z","end":"2026-05-11T20:59:55.060395Z","steps":["trace[1097815915] 'process raft request' (duration: 717.108142ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T20:59:55.060404Z","caller":"traceutil/trace.go:171","msg":"trace[1634367618] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.003367392s","start":"2026-05-11T20:59:54.057004Z","end":"2026-05-11T20:59:55.060372Z","steps":["trace[1634367618] 'process raft request' (duration: 647.266218ms)","trace[1634367618] 'compare' (duration: 355.494958ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T20:59:55.060469Z","caller":"traceutil/trace.go:171","msg":"trace[702087843] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"723.065891ms","start":"2026-05-11T20:59:54.337377Z","end":"2026-05-11T20:59:55.060442Z","steps":["trace[702087843] 'read index received' (duration: 366.743455ms)","trace[702087843] 'applied index is now lower than readState.Index' (duration: 356.320816ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T20:59:55.060556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T20:59:54.056969Z","time spent":"1.003492343s","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T20:59:55.060666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135567878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T20:59:55.060698Z","caller":"traceutil/trace.go:171","msg":"trace[394533053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"1.135656558s","start":"2026-05-11T20:59:53.925034Z","end":"2026-05-11T20:59:55.060691Z","steps":["trace[394533053] 'agreement among raft nodes before linearized reading' (duration: 1.135519107s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T20:59:55.060483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T20:59:54.343207Z","time spent":"717.244024ms","remote":"127.0.0.1:38426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T20:59:55.060771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T20:59:53.925012Z","time spent":"1.135705878s","remote":"127.0.0.1:38426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T20:59:55.060785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.293091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-11T20:59:55.060709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.103567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T20:59:55.060819Z","caller":"traceutil/trace.go:171","msg":"trace[1698591432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"307.363772ms","start":"2026-05-11T20:59:54.753444Z","end":"2026-05-11T20:59:55.060808Z","steps":["trace[1698591432] 'agreement among raft nodes before linearized reading' (duration: 307.242731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T20:59:55.060849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T20:59:54.753424Z","time spent":"307.418973ms","remote":"127.0.0.1:38426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T20:59:55.060853Z","caller":"traceutil/trace.go:171","msg":"trace[51325049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"411.27142ms","start":"2026-05-11T20:59:54.649563Z","end":"2026-05-11T20:59:55.060835Z","steps":["trace[51325049] 'agreement among raft nodes before linearized reading' (duration: 411.115988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T20:59:55.060889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T20:59:54.649544Z","time spent":"411.33796ms","remote":"127.0.0.1:38172","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-05-11T21:01:44.840877Z","caller":"traceutil/trace.go:171","msg":"trace[1001117582] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"190.258728ms","start":"2026-05-11T21:01:44.650596Z","end":"2026-05-11T21:01:44.840855Z","steps":["trace[1001117582] 'read index received' (duration: 156.403519ms)","trace[1001117582] 'applied index is now lower than readState.Index' (duration: 33.854349ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:01:44.840891Z","caller":"traceutil/trace.go:171","msg":"trace[1492390528] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"280.912686ms","start":"2026-05-11T21:01:44.55995Z","end":"2026-05-11T21:01:44.840863Z","steps":["trace[1492390528] 'process raft request' (duration: 247.010806ms)","trace[1492390528] 'compare' (duration: 33.19343ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:01:44.841018Z","caller":"traceutil/trace.go:171","msg":"trace[1351555244] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"173.001704ms","start":"2026-05-11T21:01:44.668006Z","end":"2026-05-11T21:01:44.841008Z","steps":["trace[1351555244] 'process raft request' (duration: 172.764047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:01:44.841638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.626389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:01:44.84175Z","caller":"traceutil/trace.go:171","msg":"trace[896721974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1862; }","duration":"191.166934ms","start":"2026-05-11T21:01:44.650547Z","end":"2026-05-11T21:01:44.841714Z","steps":["trace[896721974] 'agreement among raft nodes before linearized reading' (duration: 190.420182ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:01:57.703862Z","caller":"traceutil/trace.go:171","msg":"trace[1957674798] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"108.659645ms","start":"2026-05-11T21:01:57.595178Z","end":"2026-05-11T21:01:57.703837Z","steps":["trace[1957674798] 'process raft request' (duration: 108.104881ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:01:58.360448Z","caller":"traceutil/trace.go:171","msg":"trace[857181994] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"161.770476ms","start":"2026-05-11T21:01:58.198645Z","end":"2026-05-11T21:01:58.360415Z","steps":["trace[857181994] 'process raft request' (duration: 161.605521ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:02:20.61607Z","caller":"traceutil/trace.go:171","msg":"trace[1824564056] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"150.638489ms","start":"2026-05-11T21:02:20.465218Z","end":"2026-05-11T21:02:20.615856Z","steps":["trace[1824564056] 'process raft request' (duration: 134.123847ms)","trace[1824564056] 'compare' (duration: 16.116004ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:02:20.826984Z","caller":"traceutil/trace.go:171","msg":"trace[1294935961] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2192; }","duration":"103.341874ms","start":"2026-05-11T21:02:20.723601Z","end":"2026-05-11T21:02:20.826943Z","steps":["trace[1294935961] 'read index received' (duration: 33.418282ms)","trace[1294935961] 'applied index is now lower than readState.Index' (duration: 69.922602ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:02:20.827191Z","caller":"traceutil/trace.go:171","msg":"trace[904293783] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"107.931084ms","start":"2026-05-11T21:02:20.719244Z","end":"2026-05-11T21:02:20.827175Z","steps":["trace[904293783] 'process raft request' (duration: 37.865989ms)","trace[904293783] 'compare' (duration: 69.332269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:02:20.827553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.932887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-05-11T21:02:20.827602Z","caller":"traceutil/trace.go:171","msg":"trace[139105470] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2112; }","duration":"104.019868ms","start":"2026-05-11T21:02:20.723568Z","end":"2026-05-11T21:02:20.827588Z","steps":["trace[139105470] 'agreement among raft nodes before linearized reading' (duration: 103.494757ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:02:21.072058Z","caller":"traceutil/trace.go:171","msg":"trace[561263764] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"127.149165ms","start":"2026-05-11T21:02:20.944875Z","end":"2026-05-11T21:02:21.072025Z","steps":["trace[561263764] 'process raft request' (duration: 126.940791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:04:00.587958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.974461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T21:04:00.588277Z","caller":"traceutil/trace.go:171","msg":"trace[1423213165] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"238.106885ms","start":"2026-05-11T21:04:00.350148Z","end":"2026-05-11T21:04:00.588255Z","steps":["trace[1423213165] 'compare' (duration: 227.625406ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:00.59124Z","caller":"traceutil/trace.go:171","msg":"trace[1930483756] linearizableReadLoop","detail":"{readStateIndex:2863; appliedIndex:2862; }","duration":"229.697716ms","start":"2026-05-11T21:04:00.361456Z","end":"2026-05-11T21:04:00.591154Z","steps":["trace[1930483756] 'read index received' (duration: 229.523863ms)","trace[1930483756] 'applied index is now lower than readState.Index' (duration: 172.833µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:04:00.591418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.939959ms","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-05-11T21:04:00.591452Z","caller":"traceutil/trace.go:171","msg":"trace[691911347] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2759; }","duration":"230.0104ms","start":"2026-05-11T21:04:00.361431Z","end":"2026-05-11T21:04:00.591441Z","steps":["trace[691911347] 'agreement among raft nodes before linearized reading' (duration: 229.918808ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:04:00.591935Z","caller":"traceutil/trace.go:171","msg":"trace[1242634561] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"238.930237ms","start":"2026-05-11T21:04:00.352983Z","end":"2026-05-11T21:04:00.591913Z","steps":["trace[1242634561] 'process raft request' (duration: 238.031005ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:49.99916Z","caller":"traceutil/trace.go:171","msg":"trace[1498524485] linearizableReadLoop","detail":"{readStateIndex:4909; appliedIndex:4908; }","duration":"112.787518ms","start":"2026-05-11T21:07:49.886346Z","end":"2026-05-11T21:07:49.999133Z","steps":["trace[1498524485] 'read index received' (duration: 112.561913ms)","trace[1498524485] 'applied index is now lower than readState.Index' (duration: 224.705µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:07:49.999191Z","caller":"traceutil/trace.go:171","msg":"trace[584469149] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"130.667411ms","start":"2026-05-11T21:07:49.868495Z","end":"2026-05-11T21:07:49.999163Z","steps":["trace[584469149] 'process raft request' (duration: 130.487297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:07:49.999443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.044294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18ae9ef31793cb32\" ","response":"range_response_count:1 size:746"} {"level":"info","ts":"2026-05-11T21:07:49.999502Z","caller":"traceutil/trace.go:171","msg":"trace[1203858068] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18ae9ef31793cb32; range_end:; response_count:1; response_revision:4730; }","duration":"113.172577ms","start":"2026-05-11T21:07:49.886313Z","end":"2026-05-11T21:07:49.999486Z","steps":["trace[1203858068] 'agreement among raft nodes before linearized reading' (duration: 112.947572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:07:50.031225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.898104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12972"} {"level":"info","ts":"2026-05-11T21:07:50.031286Z","caller":"traceutil/trace.go:171","msg":"trace[733456997] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4731; }","duration":"140.981286ms","start":"2026-05-11T21:07:49.890291Z","end":"2026-05-11T21:07:50.031272Z","steps":["trace[733456997] 'agreement among raft nodes before linearized reading' (duration: 140.835172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:07:50.031343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.076732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"} {"level":"info","ts":"2026-05-11T21:07:50.031435Z","caller":"traceutil/trace.go:171","msg":"trace[745194595] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4731; }","duration":"143.219875ms","start":"2026-05-11T21:07:49.888196Z","end":"2026-05-11T21:07:50.031416Z","steps":["trace[745194595] 'agreement among raft nodes before linearized reading' (duration: 142.966649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:07:50.032074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.135953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2195922"} {"level":"info","ts":"2026-05-11T21:07:50.032162Z","caller":"traceutil/trace.go:171","msg":"trace[1897918549] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4731; }","duration":"102.264945ms","start":"2026-05-11T21:07:49.929876Z","end":"2026-05-11T21:07:50.032141Z","steps":["trace[1897918549] 'agreement among raft nodes before linearized reading' (duration: 101.220722ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:50.212102Z","caller":"traceutil/trace.go:171","msg":"trace[559332758] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"167.346338ms","start":"2026-05-11T21:07:50.044732Z","end":"2026-05-11T21:07:50.212079Z","steps":["trace[559332758] 'process raft request' (duration: 167.109963ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:50.212288Z","caller":"traceutil/trace.go:171","msg":"trace[215580687] linearizableReadLoop","detail":"{readStateIndex:4911; appliedIndex:4910; }","duration":"181.024088ms","start":"2026-05-11T21:07:50.031061Z","end":"2026-05-11T21:07:50.212085Z","steps":["trace[215580687] 'read index received' (duration: 104.103082ms)","trace[215580687] 'applied index is now lower than readState.Index' (duration: 76.913466ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:07:50.212172Z","caller":"traceutil/trace.go:171","msg":"trace[1241104010] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"209.765628ms","start":"2026-05-11T21:07:50.002319Z","end":"2026-05-11T21:07:50.212084Z","steps":["trace[1241104010] 'process raft request' (duration: 132.76696ms)","trace[1241104010] 'compare' (duration: 76.60937ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:07:50.212272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.929102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"warn","ts":"2026-05-11T21:07:50.21247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.700077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-575cc54b47-wdwnp.18ae9ef2e2abe032\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-05-11T21:07:50.212592Z","caller":"traceutil/trace.go:171","msg":"trace[2012299243] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-575cc54b47-wdwnp.18ae9ef2e2abe032; range_end:; response_count:1; response_revision:4733; }","duration":"209.85417ms","start":"2026-05-11T21:07:50.002716Z","end":"2026-05-11T21:07:50.21257Z","steps":["trace[2012299243] 'agreement among raft nodes before linearized reading' (duration: 209.377789ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:50.212494Z","caller":"traceutil/trace.go:171","msg":"trace[1397959768] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4733; }","duration":"189.101865ms","start":"2026-05-11T21:07:50.023294Z","end":"2026-05-11T21:07:50.212396Z","steps":["trace[1397959768] 'agreement among raft nodes before linearized reading' (duration: 188.797008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:07:50.218299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.220981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"warn","ts":"2026-05-11T21:07:50.21881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.341188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-05-11T21:07:50.218849Z","caller":"traceutil/trace.go:171","msg":"trace[1737494390] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4734; }","duration":"182.403578ms","start":"2026-05-11T21:07:50.036436Z","end":"2026-05-11T21:07:50.21884Z","steps":["trace[1737494390] 'agreement among raft nodes before linearized reading' (duration: 182.300946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:07:50.219064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.92557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-26d75e49-fb40-4df4-8cc1-a06fd4b040d5\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-11T21:07:50.21915Z","caller":"traceutil/trace.go:171","msg":"trace[1150755695] range","detail":"{range_begin:/registry/persistentvolumes/pvc-26d75e49-fb40-4df4-8cc1-a06fd4b040d5; range_end:; response_count:1; response_revision:4734; }","duration":"183.072213ms","start":"2026-05-11T21:07:50.036053Z","end":"2026-05-11T21:07:50.219125Z","steps":["trace[1150755695] 'agreement among raft nodes before linearized reading' (duration: 182.788086ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:50.218412Z","caller":"traceutil/trace.go:171","msg":"trace[822456726] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4734; }","duration":"154.371834ms","start":"2026-05-11T21:07:50.064013Z","end":"2026-05-11T21:07:50.218385Z","steps":["trace[822456726] 'agreement among raft nodes before linearized reading' (duration: 154.129989ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:50.573986Z","caller":"traceutil/trace.go:171","msg":"trace[38093326] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"191.321334ms","start":"2026-05-11T21:07:50.382643Z","end":"2026-05-11T21:07:50.573964Z","steps":["trace[38093326] 'process raft request' (duration: 186.787595ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:50.607636Z","caller":"traceutil/trace.go:171","msg":"trace[376158511] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"129.969779ms","start":"2026-05-11T21:07:50.477644Z","end":"2026-05-11T21:07:50.607614Z","steps":["trace[376158511] 'process raft request' (duration: 129.851176ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:03.224307Z","caller":"traceutil/trace.go:171","msg":"trace[764989940] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"141.237049ms","start":"2026-05-11T21:08:03.083033Z","end":"2026-05-11T21:08:03.22427Z","steps":["trace[764989940] 'process raft request' (duration: 93.118884ms)","trace[764989940] 'compare' (duration: 47.832069ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:08:03.268145Z","caller":"traceutil/trace.go:171","msg":"trace[260403652] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5105; }","duration":"161.636873ms","start":"2026-05-11T21:08:03.106483Z","end":"2026-05-11T21:08:03.26812Z","steps":["trace[260403652] 'read index received' (duration: 69.618623ms)","trace[260403652] 'applied index is now lower than readState.Index' (duration: 92.01696ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:08:03.268204Z","caller":"traceutil/trace.go:171","msg":"trace[1112879323] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"184.126362ms","start":"2026-05-11T21:08:03.084052Z","end":"2026-05-11T21:08:03.268178Z","steps":["trace[1112879323] 'process raft request' (duration: 183.977129ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:03.268212Z","caller":"traceutil/trace.go:171","msg":"trace[1458555281] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"184.879449ms","start":"2026-05-11T21:08:03.083266Z","end":"2026-05-11T21:08:03.268145Z","steps":["trace[1458555281] 'process raft request' (duration: 184.644854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:08:03.268431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.936129ms","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-05-11T21:08:03.268477Z","caller":"traceutil/trace.go:171","msg":"trace[552643563] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4925; }","duration":"162.028331ms","start":"2026-05-11T21:08:03.106436Z","end":"2026-05-11T21:08:03.268464Z","steps":["trace[552643563] 'agreement among raft nodes before linearized reading' (duration: 161.907129ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:03.268596Z","caller":"traceutil/trace.go:171","msg":"trace[392733315] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"183.737384ms","start":"2026-05-11T21:08:03.084852Z","end":"2026-05-11T21:08:03.268589Z","steps":["trace[392733315] 'process raft request' (duration: 183.226593ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:35.102164Z","caller":"traceutil/trace.go:171","msg":"trace[1227385146] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"217.13537ms","start":"2026-05-11T21:08:34.885005Z","end":"2026-05-11T21:08:35.10214Z","steps":["trace[1227385146] 'process raft request' (duration: 216.848954ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:35.240453Z","caller":"traceutil/trace.go:171","msg":"trace[890002567] linearizableReadLoop","detail":"{readStateIndex:5410; appliedIndex:5408; }","duration":"133.635924ms","start":"2026-05-11T21:08:35.106789Z","end":"2026-05-11T21:08:35.240424Z","steps":["trace[890002567] 'read index received' (duration: 53.196984ms)","trace[890002567] 'applied index is now lower than readState.Index' (duration: 80.43786ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:08:35.240702Z","caller":"traceutil/trace.go:171","msg":"trace[2056442735] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"134.268357ms","start":"2026-05-11T21:08:35.106407Z","end":"2026-05-11T21:08:35.240675Z","steps":["trace[2056442735] 'process raft request' (duration: 53.651894ms)","trace[2056442735] 'compare' (duration: 80.123983ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:08:35.240707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.904979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11917"} {"level":"info","ts":"2026-05-11T21:08:35.240895Z","caller":"traceutil/trace.go:171","msg":"trace[1779716300] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5219; }","duration":"134.051032ms","start":"2026-05-11T21:08:35.106775Z","end":"2026-05-11T21:08:35.240826Z","steps":["trace[1779716300] 'agreement among raft nodes before linearized reading' (duration: 133.763637ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:35.241122Z","caller":"traceutil/trace.go:171","msg":"trace[1059649153] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"134.457341ms","start":"2026-05-11T21:08:35.106649Z","end":"2026-05-11T21:08:35.241106Z","steps":["trace[1059649153] 'process raft request' (duration: 133.712446ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:09:18.059983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2851} {"level":"info","ts":"2026-05-11T21:09:18.152853Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2851,"took":"91.359471ms","hash":614767199,"current-db-size-bytes":53780480,"current-db-size":"54 MB","current-db-size-in-use-bytes":28901376,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-11T21:09:18.152903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":614767199,"revision":2851,"compact-revision":-1} {"level":"info","ts":"2026-05-11T21:12:02.890485Z","caller":"traceutil/trace.go:171","msg":"trace[989357131] linearizableReadLoop","detail":"{readStateIndex:6912; appliedIndex:6911; }","duration":"121.456961ms","start":"2026-05-11T21:12:02.769004Z","end":"2026-05-11T21:12:02.890461Z","steps":["trace[989357131] 'read index received' (duration: 121.420051ms)","trace[989357131] 'applied index is now lower than readState.Index' (duration: 35.82µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:12:02.890572Z","caller":"traceutil/trace.go:171","msg":"trace[509312698] transaction","detail":"{read_only:false; response_revision:6668; number_of_response:1; }","duration":"165.879051ms","start":"2026-05-11T21:12:02.724659Z","end":"2026-05-11T21:12:02.890538Z","steps":["trace[509312698] 'process raft request' (duration: 165.643727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:12:02.89074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.684696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:12:02.890791Z","caller":"traceutil/trace.go:171","msg":"trace[1594924531] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:6668; }","duration":"121.822778ms","start":"2026-05-11T21:12:02.768953Z","end":"2026-05-11T21:12:02.890776Z","steps":["trace[1594924531] 'agreement among raft nodes before linearized reading' (duration: 121.644725ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:14:18.077505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5528} {"level":"info","ts":"2026-05-11T21:14:18.173752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5528,"took":"94.642963ms","hash":1382715475,"current-db-size-bytes":53780480,"current-db-size":"54 MB","current-db-size-in-use-bytes":18202624,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-11T21:14:18.173831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1382715475,"revision":5528,"compact-revision":2851}