{"level":"warn","ts":"2026-05-12T05:38:24.06825Z","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-12T05:38:24.068321Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-12T05:38:24.068383Z","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-12T05:38:24.068398Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-12T05:38:24.068417Z","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-12T05:38:24.069087Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-05-12T05:38:24.069308Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-12T05:38:24.073695Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.189314ms"} {"level":"info","ts":"2026-05-12T05:38:24.080423Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-05-12T05:38:24.080475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T05:38:24.08049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-05-12T05:38:24.080497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T05:38:24.080507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-05-12T05:38:24.080533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-05-12T05:38:24.085414Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T05:38:24.088717Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T05:38:24.092072Z","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-12T05:38:24.094396Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T05:38:24.094498Z","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-12T05:38:24.094575Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T05:38:24.09459Z","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-12T05:38:24.094803Z","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-12T05:38:24.095404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-05-12T05:38:24.095498Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-12T05:38:24.098062Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-12T05:38:24.098093Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-12T05:38:24.097954Z","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-12T05:38:24.099812Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T05:38:24.099849Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T05:38:24.981644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T05:38:24.981711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T05:38:24.981747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-05-12T05:38:24.981767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-05-12T05:38:24.981781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-12T05:38:24.98182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-05-12T05:38:24.981854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-12T05:38:24.98292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T05:38:24.982871Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T05:38:24.983028Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T05:38:24.983046Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:38:24.98333Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T05:38:24.983417Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T05:38:24.984046Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:38:24.984176Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:38:24.984222Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:38:24.984867Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T05:38:24.988633Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-05-12T05:38:59.599217Z","caller":"traceutil/trace.go:171","msg":"trace[1574048848] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"312.380353ms","start":"2026-05-12T05:38:59.286805Z","end":"2026-05-12T05:38:59.599185Z","steps":["trace[1574048848] 'process raft request' (duration: 310.881398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:38:59.599747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:38:59.286761Z","time spent":"312.543554ms","remote":"127.0.0.1:44526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:39:00.073472Z","caller":"traceutil/trace.go:171","msg":"trace[2065516549] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"172.45421ms","start":"2026-05-12T05:38:59.900997Z","end":"2026-05-12T05:39:00.073452Z","steps":["trace[2065516549] 'process raft request' (duration: 172.312788ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:39:00.388839Z","caller":"traceutil/trace.go:171","msg":"trace[230728107] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"281.828005ms","start":"2026-05-12T05:39:00.106985Z","end":"2026-05-12T05:39:00.388813Z","steps":["trace[230728107] 'process raft request' (duration: 281.3273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:39:00.95657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.696185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:39:00.956682Z","caller":"traceutil/trace.go:171","msg":"trace[32477564] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"508.114994ms","start":"2026-05-12T05:39:00.448548Z","end":"2026-05-12T05:39:00.956663Z","steps":["trace[32477564] 'process raft request' (duration: 193.144246ms)","trace[32477564] 'compare' (duration: 314.461173ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:39:00.956743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:39:00.448521Z","time spent":"508.195736ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:39:00.98424Z","caller":"traceutil/trace.go:171","msg":"trace[761108023] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:593; }","duration":"458.148612ms","start":"2026-05-12T05:39:00.52607Z","end":"2026-05-12T05:39:00.984218Z","steps":["trace[761108023] 'read index received' (duration: 115.633963ms)","trace[761108023] 'applied index is now lower than readState.Index' (duration: 342.513759ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:39:00.984376Z","caller":"traceutil/trace.go:171","msg":"trace[1689403120] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"425.653575ms","start":"2026-05-12T05:39:00.558695Z","end":"2026-05-12T05:39:00.984349Z","steps":["trace[1689403120] 'process raft request' (duration: 425.482183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:39:00.98449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:39:00.558681Z","time spent":"425.733296ms","remote":"127.0.0.1:44696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:39:00.984587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.495596ms","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-12T05:39:00.98467Z","caller":"traceutil/trace.go:171","msg":"trace[791031996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"458.639577ms","start":"2026-05-12T05:39:00.526017Z","end":"2026-05-12T05:39:00.984656Z","steps":["trace[791031996] 'agreement among raft nodes before linearized reading' (duration: 458.454905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:39:00.984738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:39:00.525994Z","time spent":"458.734137ms","remote":"127.0.0.1:44696","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-12T05:39:00.984808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.707912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:39:00.984403Z","caller":"traceutil/trace.go:171","msg":"trace[1719840024] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"497.568775ms","start":"2026-05-12T05:39:00.486762Z","end":"2026-05-12T05:39:00.984331Z","steps":["trace[1719840024] 'process raft request' (duration: 497.298872ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:39:00.98489Z","caller":"traceutil/trace.go:171","msg":"trace[1992913024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"340.824103ms","start":"2026-05-12T05:39:00.644045Z","end":"2026-05-12T05:39:00.984869Z","steps":["trace[1992913024] 'agreement among raft nodes before linearized reading' (duration: 340.663251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:39:00.984927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:39:00.644032Z","time spent":"340.888713ms","remote":"127.0.0.1:44420","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-05-12T05:39:00.985075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:39:00.486728Z","time spent":"498.16697ms","remote":"127.0.0.1:44598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:39:00.985147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.327564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T05:39:00.985429Z","caller":"traceutil/trace.go:171","msg":"trace[1698997504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"420.631088ms","start":"2026-05-12T05:39:00.564781Z","end":"2026-05-12T05:39:00.985412Z","steps":["trace[1698997504] 'agreement among raft nodes before linearized reading' (duration: 419.920271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:39:00.985462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:39:00.564768Z","time spent":"420.685338ms","remote":"127.0.0.1:44696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T05:39:50.08797Z","caller":"traceutil/trace.go:171","msg":"trace[54187143] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"203.568054ms","start":"2026-05-12T05:39:49.88438Z","end":"2026-05-12T05:39:50.087948Z","steps":["trace[54187143] 'process raft request' (duration: 203.435128ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:39:50.106444Z","caller":"traceutil/trace.go:171","msg":"trace[13235722] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"167.972555ms","start":"2026-05-12T05:39:49.938462Z","end":"2026-05-12T05:39:50.106435Z","steps":["trace[13235722] 'process raft request' (duration: 167.849919ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:39:50.106604Z","caller":"traceutil/trace.go:171","msg":"trace[395909857] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1182; }","duration":"103.69645ms","start":"2026-05-12T05:39:50.002693Z","end":"2026-05-12T05:39:50.106389Z","steps":["trace[395909857] 'read index received' (duration: 85.192909ms)","trace[395909857] 'applied index is now lower than readState.Index' (duration: 18.502801ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:39:50.106727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.995363ms","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-05-12T05:39:50.106859Z","caller":"traceutil/trace.go:171","msg":"trace[195840513] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1150; }","duration":"104.198152ms","start":"2026-05-12T05:39:50.002635Z","end":"2026-05-12T05:39:50.106833Z","steps":["trace[195840513] 'agreement among raft nodes before linearized reading' (duration: 103.91411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:40:35.954253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.728915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:40:35.954967Z","caller":"traceutil/trace.go:171","msg":"trace[1438051620] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"338.55405ms","start":"2026-05-12T05:40:35.616359Z","end":"2026-05-12T05:40:35.954913Z","steps":["trace[1438051620] 'process raft request' (duration: 34.084305ms)","trace[1438051620] 'compare' (duration: 303.230212ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:40:35.955129Z","caller":"traceutil/trace.go:171","msg":"trace[1350281942] linearizableReadLoop","detail":"{readStateIndex:1868; appliedIndex:1867; }","duration":"309.933454ms","start":"2026-05-12T05:40:35.64518Z","end":"2026-05-12T05:40:35.955114Z","steps":["trace[1350281942] 'read index received' (duration: 5.211021ms)","trace[1350281942] 'applied index is now lower than readState.Index' (duration: 304.721003ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:40:35.95515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:40:35.616341Z","time spent":"338.714675ms","remote":"127.0.0.1:44696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:40:35.955212Z","caller":"traceutil/trace.go:171","msg":"trace[581753772] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"296.751436ms","start":"2026-05-12T05:40:35.65845Z","end":"2026-05-12T05:40:35.955201Z","steps":["trace[581753772] 'process raft request' (duration: 296.426817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:40:35.955337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.15772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:40:35.955395Z","caller":"traceutil/trace.go:171","msg":"trace[1249735945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1802; }","duration":"310.287313ms","start":"2026-05-12T05:40:35.645094Z","end":"2026-05-12T05:40:35.955382Z","steps":["trace[1249735945] 'agreement among raft nodes before linearized reading' (duration: 310.193951ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:40:35.955027Z","caller":"traceutil/trace.go:171","msg":"trace[407648517] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"270.827112ms","start":"2026-05-12T05:40:35.684177Z","end":"2026-05-12T05:40:35.955004Z","steps":["trace[407648517] 'process raft request' (duration: 270.77151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:40:35.955445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:40:35.644883Z","time spent":"310.54054ms","remote":"127.0.0.1:44420","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-12T05:40:36.405654Z","caller":"traceutil/trace.go:171","msg":"trace[958371620] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"120.706577ms","start":"2026-05-12T05:40:36.284904Z","end":"2026-05-12T05:40:36.405611Z","steps":["trace[958371620] 'process raft request' (duration: 120.44646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:41:10.744769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.178738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-12T05:41:10.74477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.957779ms","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-05-12T05:41:10.744936Z","caller":"traceutil/trace.go:171","msg":"trace[1859544829] linearizableReadLoop","detail":"{readStateIndex:2127; appliedIndex:2126; }","duration":"130.20114ms","start":"2026-05-12T05:41:10.614717Z","end":"2026-05-12T05:41:10.744918Z","steps":["trace[1859544829] 'read index received' (duration: 41.52µs)","trace[1859544829] 'applied index is now lower than readState.Index' (duration: 130.15824ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:41:10.744971Z","caller":"traceutil/trace.go:171","msg":"trace[455452870] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2050; }","duration":"373.204005ms","start":"2026-05-12T05:41:10.371744Z","end":"2026-05-12T05:41:10.744948Z","steps":["trace[455452870] 'range keys from in-memory index tree' (duration: 372.757895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:41:10.745239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:41:10.371724Z","time spent":"373.497001ms","remote":"127.0.0.1:44696","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-05-12T05:41:10.745069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.353464ms","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-12T05:41:10.745678Z","caller":"traceutil/trace.go:171","msg":"trace[1815366750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2051; }","duration":"130.995037ms","start":"2026-05-12T05:41:10.614669Z","end":"2026-05-12T05:41:10.745664Z","steps":["trace[1815366750] 'agreement among raft nodes before linearized reading' (duration: 130.297202ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:41:10.745158Z","caller":"traceutil/trace.go:171","msg":"trace[1950163458] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"226.152119ms","start":"2026-05-12T05:41:10.518944Z","end":"2026-05-12T05:41:10.745096Z","steps":["trace[1950163458] 'process raft request' (duration: 80.530902ms)","trace[1950163458] 'compare' (duration: 144.760359ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:41:10.746336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.78529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-12T05:41:10.746385Z","caller":"traceutil/trace.go:171","msg":"trace[141689748] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2050; }","duration":"262.871612ms","start":"2026-05-12T05:41:10.4835Z","end":"2026-05-12T05:41:10.746372Z","steps":["trace[141689748] 'range keys from in-memory index tree' (duration: 260.656446ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:41:10.748911Z","caller":"traceutil/trace.go:171","msg":"trace[1523437400] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"109.400794ms","start":"2026-05-12T05:41:10.639349Z","end":"2026-05-12T05:41:10.748749Z","steps":["trace[1523437400] 'process raft request' (duration: 109.273872ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:42.557154Z","caller":"traceutil/trace.go:171","msg":"trace[1764461714] transaction","detail":"{read_only:false; response_revision:2653; number_of_response:1; }","duration":"107.28917ms","start":"2026-05-12T05:42:42.449843Z","end":"2026-05-12T05:42:42.557132Z","steps":["trace[1764461714] 'process raft request' (duration: 100.170843ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:48.293805Z","caller":"traceutil/trace.go:171","msg":"trace[939791097] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"137.703037ms","start":"2026-05-12T05:42:48.156072Z","end":"2026-05-12T05:42:48.293775Z","steps":["trace[939791097] 'process raft request' (duration: 137.361242ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:31.097458Z","caller":"traceutil/trace.go:171","msg":"trace[693261004] transaction","detail":"{read_only:false; response_revision:4458; number_of_response:1; }","duration":"100.884523ms","start":"2026-05-12T05:46:30.99655Z","end":"2026-05-12T05:46:31.097434Z","steps":["trace[693261004] 'process raft request' (duration: 100.683198ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:35.969207Z","caller":"traceutil/trace.go:171","msg":"trace[246851959] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"283.589484ms","start":"2026-05-12T05:46:35.68558Z","end":"2026-05-12T05:46:35.96917Z","steps":["trace[246851959] 'process raft request' (duration: 254.617534ms)","trace[246851959] 'compare' (duration: 27.858302ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:46:35.969295Z","caller":"traceutil/trace.go:171","msg":"trace[572434680] linearizableReadLoop","detail":"{readStateIndex:4740; appliedIndex:4739; }","duration":"277.4742ms","start":"2026-05-12T05:46:35.691623Z","end":"2026-05-12T05:46:35.969097Z","steps":["trace[572434680] 'read index received' (duration: 248.672524ms)","trace[572434680] 'applied index is now lower than readState.Index' (duration: 28.798846ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:46:35.969647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.783868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14496"} {"level":"warn","ts":"2026-05-12T05:46:35.96975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.406148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:46:35.969777Z","caller":"traceutil/trace.go:171","msg":"trace[1337956883] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4566; }","duration":"278.148677ms","start":"2026-05-12T05:46:35.691589Z","end":"2026-05-12T05:46:35.969737Z","steps":["trace[1337956883] 'agreement among raft nodes before linearized reading' (duration: 277.665645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:35.969715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.225493ms","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":"warn","ts":"2026-05-12T05:46:35.969842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.477082ms","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-05-12T05:46:35.969882Z","caller":"traceutil/trace.go:171","msg":"trace[2894543] 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:4566; }","duration":"277.427608ms","start":"2026-05-12T05:46:35.69244Z","end":"2026-05-12T05:46:35.969868Z","steps":["trace[2894543] 'agreement among raft nodes before linearized reading' (duration: 277.196203ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:35.96989Z","caller":"traceutil/trace.go:171","msg":"trace[2005126495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4566; }","duration":"187.559415ms","start":"2026-05-12T05:46:35.78232Z","end":"2026-05-12T05:46:35.969879Z","steps":["trace[2005126495] 'agreement among raft nodes before linearized reading' (duration: 187.421811ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:35.96979Z","caller":"traceutil/trace.go:171","msg":"trace[106200445] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:4566; }","duration":"148.511161ms","start":"2026-05-12T05:46:35.821271Z","end":"2026-05-12T05:46:35.969782Z","steps":["trace[106200445] 'agreement among raft nodes before linearized reading' (duration: 148.411219ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:36.126426Z","caller":"traceutil/trace.go:171","msg":"trace[1432385320] transaction","detail":"{read_only:false; response_revision:4567; number_of_response:1; }","duration":"149.756753ms","start":"2026-05-12T05:46:35.976631Z","end":"2026-05-12T05:46:36.126388Z","steps":["trace[1432385320] 'process raft request' (duration: 96.612538ms)","trace[1432385320] 'compare' (duration: 52.995411ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:46:36.191639Z","caller":"traceutil/trace.go:171","msg":"trace[789098707] linearizableReadLoop","detail":"{readStateIndex:4743; appliedIndex:4741; }","duration":"168.091861ms","start":"2026-05-12T05:46:36.023529Z","end":"2026-05-12T05:46:36.191621Z","steps":["trace[789098707] 'read index received' (duration: 49.725418ms)","trace[789098707] 'applied index is now lower than readState.Index' (duration: 118.365673ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:46:36.191826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.276647ms","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-05-12T05:46:36.191871Z","caller":"traceutil/trace.go:171","msg":"trace[663801767] 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:4568; }","duration":"168.360778ms","start":"2026-05-12T05:46:36.023496Z","end":"2026-05-12T05:46:36.191857Z","steps":["trace[663801767] 'agreement among raft nodes before linearized reading' (duration: 168.226065ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:36.191848Z","caller":"traceutil/trace.go:171","msg":"trace[405449341] transaction","detail":"{read_only:false; response_revision:4568; number_of_response:1; }","duration":"211.944025ms","start":"2026-05-12T05:46:35.979865Z","end":"2026-05-12T05:46:36.191809Z","steps":["trace[405449341] 'process raft request' (duration: 211.644047ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:36.19851Z","caller":"traceutil/trace.go:171","msg":"trace[1107373501] transaction","detail":"{read_only:false; response_revision:4569; number_of_response:1; }","duration":"118.840395ms","start":"2026-05-12T05:46:36.07965Z","end":"2026-05-12T05:46:36.198491Z","steps":["trace[1107373501] 'process raft request' (duration: 118.607529ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:41.748527Z","caller":"traceutil/trace.go:171","msg":"trace[20428456] linearizableReadLoop","detail":"{readStateIndex:4855; appliedIndex:4854; }","duration":"112.781062ms","start":"2026-05-12T05:46:41.635722Z","end":"2026-05-12T05:46:41.748504Z","steps":["trace[20428456] 'read index received' (duration: 112.600948ms)","trace[20428456] 'applied index is now lower than readState.Index' (duration: 179.194µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:46:41.748657Z","caller":"traceutil/trace.go:171","msg":"trace[1027309570] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"155.14733ms","start":"2026-05-12T05:46:41.593489Z","end":"2026-05-12T05:46:41.748637Z","steps":["trace[1027309570] 'process raft request' (duration: 154.858193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:41.749351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.595952ms","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-12T05:46:41.750074Z","caller":"traceutil/trace.go:171","msg":"trace[145215099] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4679; }","duration":"114.375441ms","start":"2026-05-12T05:46:41.635678Z","end":"2026-05-12T05:46:41.750054Z","steps":["trace[145215099] 'agreement among raft nodes before linearized reading' (duration: 113.540121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:41.749512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.281371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:46:41.750243Z","caller":"traceutil/trace.go:171","msg":"trace[958561805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4679; }","duration":"107.042279ms","start":"2026-05-12T05:46:41.643175Z","end":"2026-05-12T05:46:41.750218Z","steps":["trace[958561805] 'agreement among raft nodes before linearized reading' (duration: 106.235129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:41.749712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.201014ms","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-12T05:46:41.75045Z","caller":"traceutil/trace.go:171","msg":"trace[1135897662] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4679; }","duration":"107.969053ms","start":"2026-05-12T05:46:41.64246Z","end":"2026-05-12T05:46:41.750429Z","steps":["trace[1135897662] 'agreement among raft nodes before linearized reading' (duration: 106.298651ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:41.967257Z","caller":"traceutil/trace.go:171","msg":"trace[69026985] linearizableReadLoop","detail":"{readStateIndex:4856; appliedIndex:4855; }","duration":"193.185612ms","start":"2026-05-12T05:46:41.774047Z","end":"2026-05-12T05:46:41.967232Z","steps":["trace[69026985] 'read index received' (duration: 143.288847ms)","trace[69026985] 'applied index is now lower than readState.Index' (duration: 49.895845ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:46:41.967407Z","caller":"traceutil/trace.go:171","msg":"trace[775799078] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"211.208368ms","start":"2026-05-12T05:46:41.756154Z","end":"2026-05-12T05:46:41.967363Z","steps":["trace[775799078] 'process raft request' (duration: 161.231581ms)","trace[775799078] 'compare' (duration: 49.71778ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:46:41.967456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.388297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-12T05:46:41.967642Z","caller":"traceutil/trace.go:171","msg":"trace[1993813613] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4680; }","duration":"193.612723ms","start":"2026-05-12T05:46:41.77401Z","end":"2026-05-12T05:46:41.967623Z","steps":["trace[1993813613] 'agreement among raft nodes before linearized reading' (duration: 193.312626ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:42.299294Z","caller":"traceutil/trace.go:171","msg":"trace[954278764] linearizableReadLoop","detail":"{readStateIndex:4862; appliedIndex:4860; }","duration":"161.882346ms","start":"2026-05-12T05:46:42.137393Z","end":"2026-05-12T05:46:42.299275Z","steps":["trace[954278764] 'read index received' (duration: 63.903228ms)","trace[954278764] 'applied index is now lower than readState.Index' (duration: 97.978368ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:46:42.29941Z","caller":"traceutil/trace.go:171","msg":"trace[1823032983] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"187.646582ms","start":"2026-05-12T05:46:42.111739Z","end":"2026-05-12T05:46:42.299386Z","steps":["trace[1823032983] 'process raft request' (duration: 89.557461ms)","trace[1823032983] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; req_size:13276; } (duration: 97.773813ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:46:42.299507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.506519ms","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-05-12T05:46:42.299541Z","caller":"traceutil/trace.go:171","msg":"trace[1796810042] 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:4686; }","duration":"155.604591ms","start":"2026-05-12T05:46:42.143923Z","end":"2026-05-12T05:46:42.299528Z","steps":["trace[1796810042] 'agreement among raft nodes before linearized reading' (duration: 155.467807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:42.299621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.607375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:46:42.299644Z","caller":"traceutil/trace.go:171","msg":"trace[1735830097] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4686; }","duration":"128.657935ms","start":"2026-05-12T05:46:42.170979Z","end":"2026-05-12T05:46:42.299637Z","steps":["trace[1735830097] 'agreement among raft nodes before linearized reading' (duration: 128.600724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:42.299747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.029217ms","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-12T05:46:42.299729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.314547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-12T05:46:42.299778Z","caller":"traceutil/trace.go:171","msg":"trace[1120243242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4686; }","duration":"138.085639ms","start":"2026-05-12T05:46:42.161683Z","end":"2026-05-12T05:46:42.299768Z","steps":["trace[1120243242] 'agreement among raft nodes before linearized reading' (duration: 137.983526ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:42.299797Z","caller":"traceutil/trace.go:171","msg":"trace[1652537407] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4686; }","duration":"162.418959ms","start":"2026-05-12T05:46:42.137359Z","end":"2026-05-12T05:46:42.299778Z","steps":["trace[1652537407] 'agreement among raft nodes before linearized reading' (duration: 162.027079ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:42.2998Z","caller":"traceutil/trace.go:171","msg":"trace[331332392] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"167.802052ms","start":"2026-05-12T05:46:42.131945Z","end":"2026-05-12T05:46:42.299747Z","steps":["trace[331332392] 'process raft request' (duration: 167.282909ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:42.508141Z","caller":"traceutil/trace.go:171","msg":"trace[1042885748] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"200.092879ms","start":"2026-05-12T05:46:42.308026Z","end":"2026-05-12T05:46:42.508119Z","steps":["trace[1042885748] 'process raft request' (duration: 200.032548ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:42.508245Z","caller":"traceutil/trace.go:171","msg":"trace[2033130733] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"200.203461ms","start":"2026-05-12T05:46:42.307996Z","end":"2026-05-12T05:46:42.508199Z","steps":["trace[2033130733] 'process raft request' (duration: 130.45393ms)","trace[2033130733] 'compare' (duration: 69.496355ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:46:42.519287Z","caller":"traceutil/trace.go:171","msg":"trace[1503611153] linearizableReadLoop","detail":"{readStateIndex:4866; appliedIndex:4862; }","duration":"210.910146ms","start":"2026-05-12T05:46:42.308268Z","end":"2026-05-12T05:46:42.519178Z","steps":["trace[1503611153] 'read index received' (duration: 130.45978ms)","trace[1503611153] 'applied index is now lower than readState.Index' (duration: 80.449096ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:46:42.519338Z","caller":"traceutil/trace.go:171","msg":"trace[511302786] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"211.207423ms","start":"2026-05-12T05:46:42.308101Z","end":"2026-05-12T05:46:42.519309Z","steps":["trace[511302786] 'process raft request' (duration: 210.945387ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:42.519503Z","caller":"traceutil/trace.go:171","msg":"trace[11073947] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"211.146222ms","start":"2026-05-12T05:46:42.308333Z","end":"2026-05-12T05:46:42.51948Z","steps":["trace[11073947] 'process raft request' (duration: 210.772762ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:42.519608Z","caller":"traceutil/trace.go:171","msg":"trace[1602823288] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"211.533041ms","start":"2026-05-12T05:46:42.30804Z","end":"2026-05-12T05:46:42.519573Z","steps":["trace[1602823288] 'process raft request' (duration: 210.785332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:42.519706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.304035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-05-12T05:46:42.519772Z","caller":"traceutil/trace.go:171","msg":"trace[111019625] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4691; }","duration":"211.51945ms","start":"2026-05-12T05:46:42.308237Z","end":"2026-05-12T05:46:42.519757Z","steps":["trace[111019625] 'agreement among raft nodes before linearized reading' (duration: 211.317015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:42.519782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.49913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-12T05:46:42.51982Z","caller":"traceutil/trace.go:171","msg":"trace[954059093] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4691; }","duration":"211.547272ms","start":"2026-05-12T05:46:42.308261Z","end":"2026-05-12T05:46:42.519808Z","steps":["trace[954059093] 'agreement among raft nodes before linearized reading' (duration: 211.419829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:42.519939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.881905ms","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-12T05:46:42.519983Z","caller":"traceutil/trace.go:171","msg":"trace[811111912] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4691; }","duration":"163.949417ms","start":"2026-05-12T05:46:42.35602Z","end":"2026-05-12T05:46:42.519969Z","steps":["trace[811111912] 'agreement among raft nodes before linearized reading' (duration: 163.885875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:46:42.520091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.535063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-12T05:46:42.520129Z","caller":"traceutil/trace.go:171","msg":"trace[1057808817] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4691; }","duration":"190.602004ms","start":"2026-05-12T05:46:42.329513Z","end":"2026-05-12T05:46:42.520115Z","steps":["trace[1057808817] 'agreement among raft nodes before linearized reading' (duration: 190.526312ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:46:53.751256Z","caller":"traceutil/trace.go:171","msg":"trace[1989956314] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"149.174182ms","start":"2026-05-12T05:46:53.602036Z","end":"2026-05-12T05:46:53.75121Z","steps":["trace[1989956314] 'process raft request' (duration: 112.315699ms)","trace[1989956314] 'compare' (duration: 36.679969ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:48:25.01308Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2877} {"level":"info","ts":"2026-05-12T05:48:25.096322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2877,"took":"82.145989ms","hash":1915370237,"current-db-size-bytes":54423552,"current-db-size":"54 MB","current-db-size-in-use-bytes":29388800,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-12T05:48:25.096398Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1915370237,"revision":2877,"compact-revision":-1} {"level":"info","ts":"2026-05-12T05:53:25.017946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5601} {"level":"info","ts":"2026-05-12T05:53:25.090598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5601,"took":"71.166926ms","hash":2469808033,"current-db-size-bytes":54423552,"current-db-size":"54 MB","current-db-size-in-use-bytes":18182144,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-12T05:53:25.090661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2469808033,"revision":5601,"compact-revision":2877}