{"level":"warn","ts":"2026-08-11T18:10:30.678824Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T18:10:30.678904Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-11T18:10:30.678971Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T18:10:30.678984Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-08-11T18:10:30.678999Z","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-08-11T18:10:30.679586Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-08-11T18:10:30.679651Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-11T18:10:30.684909Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.062178ms"} {"level":"info","ts":"2026-08-11T18:10:30.691745Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-08-11T18:10:30.691802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T18:10:30.69182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-08-11T18:10:30.69183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T18:10:30.691839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-08-11T18:10:30.691866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-08-11T18:10:30.700352Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T18:10:30.701892Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T18:10:30.703663Z","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-08-11T18:10:30.706953Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T18:10:30.707413Z","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-08-11T18:10:30.707515Z","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-08-11T18:10:30.707537Z","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-08-11T18:10:30.70794Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T18:10:30.708866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-08-11T18:10:30.709136Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-08-11T18:10:30.72374Z","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-08-11T18:10:30.723965Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T18:10:30.723994Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T18:10:30.724106Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-08-11T18:10:30.724116Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-08-11T18:10:30.794227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T18:10:30.79429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T18:10:30.794446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-08-11T18:10:30.794469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-08-11T18:10:30.794483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-08-11T18:10:30.794495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-08-11T18:10:30.794507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-08-11T18:10:30.795336Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T18:10:30.798096Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T18:10:30.798181Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T18:10:30.798359Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T18:10:30.798444Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T18:10:30.798494Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T18:10:30.798983Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T18:10:30.799082Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T18:10:30.799111Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T18:10:30.799695Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-08-11T18:10:30.802033Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-11T18:10:54.985531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.608516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T18:10:54.985646Z","caller":"traceutil/trace.go:171","msg":"trace[2128302123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:461; }","duration":"249.792651ms","start":"2026-08-11T18:10:54.735832Z","end":"2026-08-11T18:10:54.985624Z","steps":["trace[2128302123] 'range keys from in-memory index tree' (duration: 249.505204ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:06.819613Z","caller":"traceutil/trace.go:171","msg":"trace[1581133637] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"172.817331ms","start":"2026-08-11T18:11:06.646766Z","end":"2026-08-11T18:11:06.819584Z","steps":["trace[1581133637] 'process raft request' (duration: 126.182195ms)","trace[1581133637] 'compare' (duration: 46.498272ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:11:06.917397Z","caller":"traceutil/trace.go:171","msg":"trace[430520147] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"186.481464ms","start":"2026-08-11T18:11:06.730872Z","end":"2026-08-11T18:11:06.917354Z","steps":["trace[430520147] 'process raft request' (duration: 186.256889ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:06.917432Z","caller":"traceutil/trace.go:171","msg":"trace[33622836] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"174.876931ms","start":"2026-08-11T18:11:06.742506Z","end":"2026-08-11T18:11:06.917383Z","steps":["trace[33622836] 'process raft request' (duration: 174.712737ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:06.917579Z","caller":"traceutil/trace.go:171","msg":"trace[1120696881] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"169.037979ms","start":"2026-08-11T18:11:06.748527Z","end":"2026-08-11T18:11:06.917565Z","steps":["trace[1120696881] 'process raft request' (duration: 168.784843ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:06.917635Z","caller":"traceutil/trace.go:171","msg":"trace[1283785053] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"186.897214ms","start":"2026-08-11T18:11:06.730727Z","end":"2026-08-11T18:11:06.917624Z","steps":["trace[1283785053] 'process raft request' (duration: 186.3201ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:06.917701Z","caller":"traceutil/trace.go:171","msg":"trace[764733065] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"183.792948ms","start":"2026-08-11T18:11:06.733898Z","end":"2026-08-11T18:11:06.917691Z","steps":["trace[764733065] 'process raft request' (duration: 183.260875ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:06.917719Z","caller":"traceutil/trace.go:171","msg":"trace[523429316] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"167.502942ms","start":"2026-08-11T18:11:06.750205Z","end":"2026-08-11T18:11:06.917708Z","steps":["trace[523429316] 'process raft request' (duration: 167.216775ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:06.917746Z","caller":"traceutil/trace.go:171","msg":"trace[1236147541] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"169.130891ms","start":"2026-08-11T18:11:06.748581Z","end":"2026-08-11T18:11:06.917712Z","steps":["trace[1236147541] 'process raft request' (duration: 168.790832ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:06.917866Z","caller":"traceutil/trace.go:171","msg":"trace[1270438527] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"166.982919ms","start":"2026-08-11T18:11:06.750865Z","end":"2026-08-11T18:11:06.917848Z","steps":["trace[1270438527] 'process raft request' (duration: 166.62658ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:06.918036Z","caller":"traceutil/trace.go:171","msg":"trace[2106113846] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"159.981488ms","start":"2026-08-11T18:11:06.758048Z","end":"2026-08-11T18:11:06.918029Z","steps":["trace[2106113846] 'process raft request' (duration: 159.831965ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:06.918055Z","caller":"traceutil/trace.go:171","msg":"trace[943158064] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"146.993882ms","start":"2026-08-11T18:11:06.771051Z","end":"2026-08-11T18:11:06.918044Z","steps":["trace[943158064] 'process raft request' (duration: 146.93148ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:06.918047Z","caller":"traceutil/trace.go:171","msg":"trace[558736677] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:539; }","duration":"183.743637ms","start":"2026-08-11T18:11:06.734283Z","end":"2026-08-11T18:11:06.918027Z","steps":["trace[558736677] 'read index received' (duration: 38.701153ms)","trace[558736677] 'applied index is now lower than readState.Index' (duration: 145.041514ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:11:06.918182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.882031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T18:11:06.918213Z","caller":"traceutil/trace.go:171","msg":"trace[1516988949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"183.946232ms","start":"2026-08-11T18:11:06.734259Z","end":"2026-08-11T18:11:06.918205Z","steps":["trace[1516988949] 'agreement among raft nodes before linearized reading' (duration: 183.840589ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:06.917803Z","caller":"traceutil/trace.go:171","msg":"trace[455824937] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"175.109787ms","start":"2026-08-11T18:11:06.742556Z","end":"2026-08-11T18:11:06.917666Z","steps":["trace[455824937] 'process raft request' (duration: 174.698597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:11:06.95304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.91488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T18:11:06.953203Z","caller":"traceutil/trace.go:171","msg":"trace[1044493961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"209.015673ms","start":"2026-08-11T18:11:06.744088Z","end":"2026-08-11T18:11:06.953103Z","steps":["trace[1044493961] 'agreement among raft nodes before linearized reading' (duration: 208.857349ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:13.045439Z","caller":"traceutil/trace.go:171","msg":"trace[1604638536] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"203.852153ms","start":"2026-08-11T18:11:12.841557Z","end":"2026-08-11T18:11:13.045409Z","steps":["trace[1604638536] 'read index received' (duration: 203.678059ms)","trace[1604638536] 'applied index is now lower than readState.Index' (duration: 172.924µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:11:13.045542Z","caller":"traceutil/trace.go:171","msg":"trace[768153824] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"231.970575ms","start":"2026-08-11T18:11:12.813521Z","end":"2026-08-11T18:11:13.045492Z","steps":["trace[768153824] 'process raft request' (duration: 231.670008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:11:13.045697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.11964ms","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-08-11T18:11:13.045738Z","caller":"traceutil/trace.go:171","msg":"trace[630287004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"204.207992ms","start":"2026-08-11T18:11:12.841519Z","end":"2026-08-11T18:11:13.045727Z","steps":["trace[630287004] 'agreement among raft nodes before linearized reading' (duration: 204.071239ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:13.277101Z","caller":"traceutil/trace.go:171","msg":"trace[1576923255] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"231.511621ms","start":"2026-08-11T18:11:13.045565Z","end":"2026-08-11T18:11:13.277076Z","steps":["trace[1576923255] 'read index received' (duration: 213.286609ms)","trace[1576923255] 'applied index is now lower than readState.Index' (duration: 18.223802ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:11:13.277245Z","caller":"traceutil/trace.go:171","msg":"trace[973064838] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"433.816957ms","start":"2026-08-11T18:11:12.843358Z","end":"2026-08-11T18:11:13.277175Z","steps":["trace[973064838] 'process raft request' (duration: 415.662067ms)","trace[973064838] 'compare' (duration: 17.72649ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:11:13.277344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.36805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-11T18:11:13.277399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.284571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T18:11:13.277411Z","caller":"traceutil/trace.go:171","msg":"trace[1852498126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"293.476822ms","start":"2026-08-11T18:11:12.983921Z","end":"2026-08-11T18:11:13.277398Z","steps":["trace[1852498126] 'agreement among raft nodes before linearized reading' (duration: 293.249887ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:11:13.277427Z","caller":"traceutil/trace.go:171","msg":"trace[855081890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"282.355893ms","start":"2026-08-11T18:11:12.995063Z","end":"2026-08-11T18:11:13.277419Z","steps":["trace[855081890] 'agreement among raft nodes before linearized reading' (duration: 282.261681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:11:13.278234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:11:12.843332Z","time spent":"434.035322ms","remote":"127.0.0.1:32796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T18:11:13.283211Z","caller":"traceutil/trace.go:171","msg":"trace[825175536] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"233.222032ms","start":"2026-08-11T18:11:13.049974Z","end":"2026-08-11T18:11:13.283196Z","steps":["trace[825175536] 'process raft request' (duration: 233.12618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:11:13.857795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.076513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T18:11:13.857961Z","caller":"traceutil/trace.go:171","msg":"trace[1913957933] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"380.317787ms","start":"2026-08-11T18:11:13.477629Z","end":"2026-08-11T18:11:13.857947Z","steps":["trace[1913957933] 'process raft request' (duration: 380.262045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:11:13.858036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:11:13.477596Z","time spent":"380.403459ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T18:11:13.858076Z","caller":"traceutil/trace.go:171","msg":"trace[1340861605] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"514.319293ms","start":"2026-08-11T18:11:13.343701Z","end":"2026-08-11T18:11:13.85802Z","steps":["trace[1340861605] 'process raft request' (duration: 279.859262ms)","trace[1340861605] 'compare' (duration: 233.957759ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:11:13.858293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:11:13.343686Z","time spent":"514.525098ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T18:12:47.846895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.482749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T18:12:47.847031Z","caller":"traceutil/trace.go:171","msg":"trace[747265370] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1946; }","duration":"354.285729ms","start":"2026-08-11T18:12:47.492724Z","end":"2026-08-11T18:12:47.84701Z","steps":["trace[747265370] 'read index received' (duration: 223.538124ms)","trace[747265370] 'applied index is now lower than readState.Index' (duration: 130.746135ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:12:47.847087Z","caller":"traceutil/trace.go:171","msg":"trace[1773922199] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"424.830067ms","start":"2026-08-11T18:12:47.422225Z","end":"2026-08-11T18:12:47.847055Z","steps":["trace[1773922199] 'process raft request' (duration: 294.037171ms)","trace[1773922199] 'compare' (duration: 130.149711ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:12:47.847248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.524524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e37ea966-42b1-4b37-94d2-61de88bda37d\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-11T18:12:47.84725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:12:47.422194Z","time spent":"424.970061ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T18:12:47.8473Z","caller":"traceutil/trace.go:171","msg":"trace[2137437386] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e37ea966-42b1-4b37-94d2-61de88bda37d; range_end:; response_count:1; response_revision:1879; }","duration":"354.603496ms","start":"2026-08-11T18:12:47.492683Z","end":"2026-08-11T18:12:47.847286Z","steps":["trace[2137437386] 'agreement among raft nodes before linearized reading' (duration: 354.448563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:12:47.847343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:12:47.492662Z","time spent":"354.671178ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e37ea966-42b1-4b37-94d2-61de88bda37d\" "} {"level":"warn","ts":"2026-08-11T18:12:47.847716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.795089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-11T18:12:47.84776Z","caller":"traceutil/trace.go:171","msg":"trace[497062740] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1879; }","duration":"134.878131ms","start":"2026-08-11T18:12:47.712869Z","end":"2026-08-11T18:12:47.847747Z","steps":["trace[497062740] 'agreement among raft nodes before linearized reading' (duration: 134.740768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:12:47.847937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.508742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-11T18:12:47.847995Z","caller":"traceutil/trace.go:171","msg":"trace[1618784619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1879; }","duration":"101.623055ms","start":"2026-08-11T18:12:47.746359Z","end":"2026-08-11T18:12:47.847982Z","steps":["trace[1618784619] 'agreement among raft nodes before linearized reading' (duration: 101.454361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:12:47.848008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.946579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T18:12:47.848094Z","caller":"traceutil/trace.go:171","msg":"trace[1463667954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1879; }","duration":"110.077892ms","start":"2026-08-11T18:12:47.737994Z","end":"2026-08-11T18:12:47.848072Z","steps":["trace[1463667954] 'agreement among raft nodes before linearized reading' (duration: 109.901818ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:12:48.083728Z","caller":"traceutil/trace.go:171","msg":"trace[1142780561] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"231.591861ms","start":"2026-08-11T18:12:47.852102Z","end":"2026-08-11T18:12:48.083694Z","steps":["trace[1142780561] 'process raft request' (duration: 174.860905ms)","trace[1142780561] 'compare' (duration: 56.47928ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:12:48.084075Z","caller":"traceutil/trace.go:171","msg":"trace[2095151854] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"231.787235ms","start":"2026-08-11T18:12:47.85226Z","end":"2026-08-11T18:12:48.084048Z","steps":["trace[2095151854] 'process raft request' (duration: 231.344015ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:12:48.084112Z","caller":"traceutil/trace.go:171","msg":"trace[685242156] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1947; }","duration":"182.970464ms","start":"2026-08-11T18:12:47.901128Z","end":"2026-08-11T18:12:48.084098Z","steps":["trace[685242156] 'read index received' (duration: 126.085435ms)","trace[685242156] 'applied index is now lower than readState.Index' (duration: 56.883589ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:12:48.084319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.169409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T18:12:48.084356Z","caller":"traceutil/trace.go:171","msg":"trace[1185675264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1881; }","duration":"183.263331ms","start":"2026-08-11T18:12:47.901083Z","end":"2026-08-11T18:12:48.084347Z","steps":["trace[1185675264] 'agreement among raft nodes before linearized reading' (duration: 183.090337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:12:48.109482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.764194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T18:12:48.109547Z","caller":"traceutil/trace.go:171","msg":"trace[225011548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1882; }","duration":"204.895207ms","start":"2026-08-11T18:12:47.904638Z","end":"2026-08-11T18:12:48.109533Z","steps":["trace[225011548] 'agreement among raft nodes before linearized reading' (duration: 204.690112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:12:48.661236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.58468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T18:12:48.661475Z","caller":"traceutil/trace.go:171","msg":"trace[1772104537] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"206.191607ms","start":"2026-08-11T18:12:48.455266Z","end":"2026-08-11T18:12:48.661458Z","steps":["trace[1772104537] 'process raft request' (duration: 206.153566ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:12:48.661551Z","caller":"traceutil/trace.go:171","msg":"trace[1760254444] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"443.552983ms","start":"2026-08-11T18:12:48.217959Z","end":"2026-08-11T18:12:48.661512Z","steps":["trace[1760254444] 'process raft request' (duration: 443.384689ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:12:48.661611Z","caller":"traceutil/trace.go:171","msg":"trace[1565122726] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"548.040375ms","start":"2026-08-11T18:12:48.113543Z","end":"2026-08-11T18:12:48.661583Z","steps":["trace[1565122726] 'process raft request' (duration: 331.015384ms)","trace[1565122726] 'compare' (duration: 216.238362ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:12:48.661766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:12:48.21793Z","time spent":"443.723857ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T18:12:48.661794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:12:48.113519Z","time spent":"548.188708ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T18:12:48.661798Z","caller":"traceutil/trace.go:171","msg":"trace[1473932003] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1951; }","duration":"375.086393ms","start":"2026-08-11T18:12:48.286591Z","end":"2026-08-11T18:12:48.661677Z","steps":["trace[1473932003] 'read index received' (duration: 157.594492ms)","trace[1473932003] 'applied index is now lower than readState.Index' (duration: 217.44399ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:12:48.66203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.443382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e37ea966-42b1-4b37-94d2-61de88bda37d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-11T18:12:48.662125Z","caller":"traceutil/trace.go:171","msg":"trace[972900659] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e37ea966-42b1-4b37-94d2-61de88bda37d; range_end:; response_count:1; response_revision:1886; }","duration":"375.590135ms","start":"2026-08-11T18:12:48.28652Z","end":"2026-08-11T18:12:48.662111Z","steps":["trace[972900659] 'agreement among raft nodes before linearized reading' (duration: 375.39083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:12:48.662167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:12:48.286492Z","time spent":"375.664757ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e37ea966-42b1-4b37-94d2-61de88bda37d\" "} {"level":"warn","ts":"2026-08-11T18:12:48.662419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.655364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T18:12:48.66266Z","caller":"traceutil/trace.go:171","msg":"trace[224075827] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1886; }","duration":"196.966371ms","start":"2026-08-11T18:12:48.465675Z","end":"2026-08-11T18:12:48.662641Z","steps":["trace[224075827] 'agreement among raft nodes before linearized reading' (duration: 196.634774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:13:02.09779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.729338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-11T18:13:02.097851Z","caller":"traceutil/trace.go:171","msg":"trace[1921871711] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1997; }","duration":"148.83209ms","start":"2026-08-11T18:13:01.949004Z","end":"2026-08-11T18:13:02.097836Z","steps":["trace[1921871711] 'range keys from in-memory index tree' (duration: 148.589994ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:13:02.514937Z","caller":"traceutil/trace.go:171","msg":"trace[70122242] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"186.042167ms","start":"2026-08-11T18:13:02.32887Z","end":"2026-08-11T18:13:02.514913Z","steps":["trace[70122242] 'process raft request' (duration: 185.866173ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:13:17.075476Z","caller":"traceutil/trace.go:171","msg":"trace[826415405] linearizableReadLoop","detail":"{readStateIndex:2174; appliedIndex:2173; }","duration":"183.87333ms","start":"2026-08-11T18:13:16.891574Z","end":"2026-08-11T18:13:17.075447Z","steps":["trace[826415405] 'read index received' (duration: 183.810038ms)","trace[826415405] 'applied index is now lower than readState.Index' (duration: 62.362µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:13:17.075563Z","caller":"traceutil/trace.go:171","msg":"trace[580908898] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"212.863434ms","start":"2026-08-11T18:13:16.862657Z","end":"2026-08-11T18:13:17.07552Z","steps":["trace[580908898] 'process raft request' (duration: 212.541547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:13:17.075751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.164096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T18:13:17.075801Z","caller":"traceutil/trace.go:171","msg":"trace[1964137774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2099; }","duration":"184.227257ms","start":"2026-08-11T18:13:16.891555Z","end":"2026-08-11T18:13:17.075783Z","steps":["trace[1964137774] 'agreement among raft nodes before linearized reading' (duration: 184.010302ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:13:18.566985Z","caller":"traceutil/trace.go:171","msg":"trace[395750099] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"328.295029ms","start":"2026-08-11T18:13:18.238669Z","end":"2026-08-11T18:13:18.566964Z","steps":["trace[395750099] 'process raft request' (duration: 328.151705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:13:18.567283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:13:18.238656Z","time spent":"328.406482ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T18:13:18.574467Z","caller":"traceutil/trace.go:171","msg":"trace[359765024] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2179; }","duration":"311.178071ms","start":"2026-08-11T18:13:18.263262Z","end":"2026-08-11T18:13:18.57444Z","steps":["trace[359765024] 'read index received' (duration: 303.583824ms)","trace[359765024] 'applied index is now lower than readState.Index' (duration: 7.593227ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:13:18.574696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.420557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-11T18:13:18.57474Z","caller":"traceutil/trace.go:171","msg":"trace[2021311281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2106; }","duration":"311.483469ms","start":"2026-08-11T18:13:18.263246Z","end":"2026-08-11T18:13:18.57473Z","steps":["trace[2021311281] 'agreement among raft nodes before linearized reading' (duration: 311.307934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:13:18.574776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:13:18.263202Z","time spent":"311.56517ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-11T18:13:18.575169Z","caller":"traceutil/trace.go:171","msg":"trace[211636211] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"335.571118ms","start":"2026-08-11T18:13:18.238965Z","end":"2026-08-11T18:13:18.574536Z","steps":["trace[211636211] 'process raft request' (duration: 335.313662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:13:18.575444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:13:18.238957Z","time spent":"336.260085ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T18:13:18.688238Z","caller":"traceutil/trace.go:171","msg":"trace[461905019] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"108.757791ms","start":"2026-08-11T18:13:18.579441Z","end":"2026-08-11T18:13:18.688199Z","steps":["trace[461905019] 'process raft request' (duration: 28.78718ms)","trace[461905019] 'compare' (duration: 79.49701ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:13:18.68839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.969153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T18:13:18.688506Z","caller":"traceutil/trace.go:171","msg":"trace[801134799] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2107; }","duration":"102.113246ms","start":"2026-08-11T18:13:18.586347Z","end":"2026-08-11T18:13:18.68846Z","steps":["trace[801134799] 'agreement among raft nodes before linearized reading' (duration: 101.944532ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:13:18.688343Z","caller":"traceutil/trace.go:171","msg":"trace[625114998] linearizableReadLoop","detail":"{readStateIndex:2182; appliedIndex:2181; }","duration":"101.495952ms","start":"2026-08-11T18:13:18.586385Z","end":"2026-08-11T18:13:18.68788Z","steps":["trace[625114998] 'read index received' (duration: 21.88644ms)","trace[625114998] 'applied index is now lower than readState.Index' (duration: 79.608532ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:13:18.988603Z","caller":"traceutil/trace.go:171","msg":"trace[367146098] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"105.101446ms","start":"2026-08-11T18:13:18.883465Z","end":"2026-08-11T18:13:18.988567Z","steps":["trace[367146098] 'process raft request' (duration: 44.789772ms)","trace[367146098] 'compare' (duration: 60.183201ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:13:19.291791Z","caller":"traceutil/trace.go:171","msg":"trace[239228314] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"209.72616ms","start":"2026-08-11T18:13:19.082025Z","end":"2026-08-11T18:13:19.291751Z","steps":["trace[239228314] 'process raft request' (duration: 110.289456ms)","trace[239228314] 'compare' (duration: 99.210409ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:13:19.600399Z","caller":"traceutil/trace.go:171","msg":"trace[826224861] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"507.096909ms","start":"2026-08-11T18:13:19.093272Z","end":"2026-08-11T18:13:19.600369Z","steps":["trace[826224861] 'process raft request' (duration: 495.293324ms)","trace[826224861] 'compare' (duration: 11.512068ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:13:19.600553Z","caller":"traceutil/trace.go:171","msg":"trace[1818066176] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2183; }","duration":"493.938163ms","start":"2026-08-11T18:13:19.106602Z","end":"2026-08-11T18:13:19.60054Z","steps":["trace[1818066176] 'read index received' (duration: 85.805817ms)","trace[1818066176] 'applied index is now lower than readState.Index' (duration: 408.131456ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:13:19.600569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:13:19.09325Z","time spent":"507.233591ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T18:13:19.600719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.122766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T18:13:19.600748Z","caller":"traceutil/trace.go:171","msg":"trace[417536532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2110; }","duration":"494.191748ms","start":"2026-08-11T18:13:19.106549Z","end":"2026-08-11T18:13:19.600741Z","steps":["trace[417536532] 'agreement among raft nodes before linearized reading' (duration: 494.092106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:13:19.600768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:13:19.106528Z","time spent":"494.234789ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-11T18:13:19.610174Z","caller":"traceutil/trace.go:171","msg":"trace[1961035905] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"141.510072ms","start":"2026-08-11T18:13:19.468653Z","end":"2026-08-11T18:13:19.610163Z","steps":["trace[1961035905] 'process raft request' (duration: 140.98021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:13:19.610408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.304634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-11T18:13:19.610442Z","caller":"traceutil/trace.go:171","msg":"trace[1913724181] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2112; }","duration":"400.378176ms","start":"2026-08-11T18:13:19.210053Z","end":"2026-08-11T18:13:19.610431Z","steps":["trace[1913724181] 'agreement among raft nodes before linearized reading' (duration: 400.281123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:13:19.610464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:13:19.210038Z","time spent":"400.420656ms","remote":"127.0.0.1:38498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-11T18:13:19.610604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.45469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-11T18:13:19.610627Z","caller":"traceutil/trace.go:171","msg":"trace[851409233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2112; }","duration":"263.507671ms","start":"2026-08-11T18:13:19.347114Z","end":"2026-08-11T18:13:19.610621Z","steps":["trace[851409233] 'agreement among raft nodes before linearized reading' (duration: 263.45091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:13:19.610701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.53563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T18:13:19.61072Z","caller":"traceutil/trace.go:171","msg":"trace[1241114069] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2112; }","duration":"394.593781ms","start":"2026-08-11T18:13:19.216122Z","end":"2026-08-11T18:13:19.610716Z","steps":["trace[1241114069] 'agreement among raft nodes before linearized reading' (duration: 394.55227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:13:19.610738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:13:19.216103Z","time spent":"394.630272ms","remote":"127.0.0.1:32890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":15,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-08-11T18:15:15.199087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.250412ms","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-08-11T18:15:15.199829Z","caller":"traceutil/trace.go:171","msg":"trace[478021352] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2835; }","duration":"156.998417ms","start":"2026-08-11T18:15:15.042801Z","end":"2026-08-11T18:15:15.1998Z","steps":["trace[478021352] 'range keys from in-memory index tree' (duration: 156.233911ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:18:59.597529Z","caller":"traceutil/trace.go:171","msg":"trace[1607290828] linearizableReadLoop","detail":"{readStateIndex:4883; appliedIndex:4882; }","duration":"280.690951ms","start":"2026-08-11T18:18:59.316799Z","end":"2026-08-11T18:18:59.59749Z","steps":["trace[1607290828] 'read index received' (duration: 240.668218ms)","trace[1607290828] 'applied index is now lower than readState.Index' (duration: 40.021273ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:18:59.597771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.677744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T18:18:59.597801Z","caller":"traceutil/trace.go:171","msg":"trace[1043531712] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:4708; }","duration":"248.780617ms","start":"2026-08-11T18:18:59.349011Z","end":"2026-08-11T18:18:59.597792Z","steps":["trace[1043531712] 'agreement among raft nodes before linearized reading' (duration: 248.640973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:18:59.597773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.946308ms","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-08-11T18:18:59.597867Z","caller":"traceutil/trace.go:171","msg":"trace[1374204590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4708; }","duration":"281.078672ms","start":"2026-08-11T18:18:59.316767Z","end":"2026-08-11T18:18:59.597846Z","steps":["trace[1374204590] 'agreement among raft nodes before linearized reading' (duration: 280.829275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:18:59.597947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.342878ms","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-08-11T18:18:59.597995Z","caller":"traceutil/trace.go:171","msg":"trace[129303731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4708; }","duration":"223.42939ms","start":"2026-08-11T18:18:59.374548Z","end":"2026-08-11T18:18:59.597977Z","steps":["trace[129303731] 'agreement among raft nodes before linearized reading' (duration: 223.272636ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:18:59.867253Z","caller":"traceutil/trace.go:171","msg":"trace[1228906486] linearizableReadLoop","detail":"{readStateIndex:4885; appliedIndex:4884; }","duration":"139.195179ms","start":"2026-08-11T18:18:59.728029Z","end":"2026-08-11T18:18:59.867224Z","steps":["trace[1228906486] 'read index received' (duration: 71.437114ms)","trace[1228906486] 'applied index is now lower than readState.Index' (duration: 67.756915ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:18:59.867329Z","caller":"traceutil/trace.go:171","msg":"trace[483725670] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"264.565994ms","start":"2026-08-11T18:18:59.602729Z","end":"2026-08-11T18:18:59.867295Z","steps":["trace[483725670] 'process raft request' (duration: 196.643425ms)","trace[483725670] 'compare' (duration: 67.678363ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:18:59.867531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.591106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T18:18:59.867635Z","caller":"traceutil/trace.go:171","msg":"trace[556521472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4710; }","duration":"130.767081ms","start":"2026-08-11T18:18:59.736855Z","end":"2026-08-11T18:18:59.867622Z","steps":["trace[556521472] 'agreement among raft nodes before linearized reading' (duration: 130.566466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:18:59.867616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.557429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-dklmx\" ","response":"range_response_count:1 size:5151"} {"level":"info","ts":"2026-08-11T18:18:59.867728Z","caller":"traceutil/trace.go:171","msg":"trace[235643357] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-dklmx; range_end:; response_count:1; response_revision:4710; }","duration":"139.725673ms","start":"2026-08-11T18:18:59.727976Z","end":"2026-08-11T18:18:59.867701Z","steps":["trace[235643357] 'agreement among raft nodes before linearized reading' (duration: 139.397244ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:19:05.389782Z","caller":"traceutil/trace.go:171","msg":"trace[1493960028] linearizableReadLoop","detail":"{readStateIndex:4964; appliedIndex:4963; }","duration":"152.802968ms","start":"2026-08-11T18:19:05.236952Z","end":"2026-08-11T18:19:05.389755Z","steps":["trace[1493960028] 'read index received' (duration: 78.11116ms)","trace[1493960028] 'applied index is now lower than readState.Index' (duration: 74.690438ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:19:05.390017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.036604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-11T18:19:05.390058Z","caller":"traceutil/trace.go:171","msg":"trace[1903154229] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4787; }","duration":"153.096336ms","start":"2026-08-11T18:19:05.23695Z","end":"2026-08-11T18:19:05.390047Z","steps":["trace[1903154229] 'agreement among raft nodes before linearized reading' (duration: 152.90012ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:19:05.390286Z","caller":"traceutil/trace.go:171","msg":"trace[122809577] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"153.601779ms","start":"2026-08-11T18:19:05.236656Z","end":"2026-08-11T18:19:05.390258Z","steps":["trace[122809577] 'process raft request' (duration: 78.179562ms)","trace[122809577] 'compare' (duration: 74.708898ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:19:05.548157Z","caller":"traceutil/trace.go:171","msg":"trace[1924165242] linearizableReadLoop","detail":"{readStateIndex:4965; appliedIndex:4964; }","duration":"154.197765ms","start":"2026-08-11T18:19:05.393925Z","end":"2026-08-11T18:19:05.548123Z","steps":["trace[1924165242] 'read index received' (duration: 154.050271ms)","trace[1924165242] 'applied index is now lower than readState.Index' (duration: 145.604µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:19:05.548503Z","caller":"traceutil/trace.go:171","msg":"trace[1901080643] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"285.968154ms","start":"2026-08-11T18:19:05.262489Z","end":"2026-08-11T18:19:05.548457Z","steps":["trace[1901080643] 'process raft request' (duration: 285.402499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:05.549753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.756607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-11T18:19:05.549943Z","caller":"traceutil/trace.go:171","msg":"trace[1859655898] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4788; }","duration":"156.027694ms","start":"2026-08-11T18:19:05.393885Z","end":"2026-08-11T18:19:05.549913Z","steps":["trace[1859655898] 'agreement among raft nodes before linearized reading' (duration: 154.629617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:05.562539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.262415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T18:19:05.562625Z","caller":"traceutil/trace.go:171","msg":"trace[117830427] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:0; response_revision:4789; }","duration":"168.393108ms","start":"2026-08-11T18:19:05.394213Z","end":"2026-08-11T18:19:05.562606Z","steps":["trace[117830427] 'agreement among raft nodes before linearized reading' (duration: 168.269035ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:19:05.563092Z","caller":"traceutil/trace.go:171","msg":"trace[782940317] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"167.394551ms","start":"2026-08-11T18:19:05.395685Z","end":"2026-08-11T18:19:05.56308Z","steps":["trace[782940317] 'process raft request' (duration: 166.353523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:05.563679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.812996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-08-11T18:19:05.563765Z","caller":"traceutil/trace.go:171","msg":"trace[1302191468] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4789; }","duration":"149.967771ms","start":"2026-08-11T18:19:05.413776Z","end":"2026-08-11T18:19:05.563744Z","steps":["trace[1302191468] 'agreement among raft nodes before linearized reading' (duration: 148.527332ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:19:05.971007Z","caller":"traceutil/trace.go:171","msg":"trace[1971693679] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"125.984702ms","start":"2026-08-11T18:19:05.844995Z","end":"2026-08-11T18:19:05.970979Z","steps":["trace[1971693679] 'process raft request' (duration: 125.776156ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:19:05.971263Z","caller":"traceutil/trace.go:171","msg":"trace[1106828111] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"120.243107ms","start":"2026-08-11T18:19:05.85099Z","end":"2026-08-11T18:19:05.971233Z","steps":["trace[1106828111] 'process raft request' (duration: 119.913518ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:19:06.081149Z","caller":"traceutil/trace.go:171","msg":"trace[74370338] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"146.968788ms","start":"2026-08-11T18:19:05.93413Z","end":"2026-08-11T18:19:06.081098Z","steps":["trace[74370338] 'process raft request' (duration: 130.450102ms)","trace[74370338] 'compare' (duration: 15.892549ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:19:06.081283Z","caller":"traceutil/trace.go:171","msg":"trace[488702425] linearizableReadLoop","detail":"{readStateIndex:4982; appliedIndex:4979; }","duration":"144.981715ms","start":"2026-08-11T18:19:05.936266Z","end":"2026-08-11T18:19:06.081248Z","steps":["trace[488702425] 'read index received' (duration: 34.585674ms)","trace[488702425] 'applied index is now lower than readState.Index' (duration: 110.393671ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T18:19:06.081496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.448475ms","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-08-11T18:19:06.081577Z","caller":"traceutil/trace.go:171","msg":"trace[2104701328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4805; }","duration":"179.574109ms","start":"2026-08-11T18:19:05.901991Z","end":"2026-08-11T18:19:06.081565Z","steps":["trace[2104701328] 'agreement among raft nodes before linearized reading' (duration: 179.322692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:06.081613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.846738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9318"} {"level":"info","ts":"2026-08-11T18:19:06.081663Z","caller":"traceutil/trace.go:171","msg":"trace[1470589110] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4805; }","duration":"232.96963ms","start":"2026-08-11T18:19:05.848678Z","end":"2026-08-11T18:19:06.081648Z","steps":["trace[1470589110] 'agreement among raft nodes before linearized reading' (duration: 232.743164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:06.081925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.351123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T18:19:06.081982Z","caller":"traceutil/trace.go:171","msg":"trace[1203368568] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4805; }","duration":"236.405494ms","start":"2026-08-11T18:19:05.845565Z","end":"2026-08-11T18:19:06.081971Z","steps":["trace[1203368568] 'agreement among raft nodes before linearized reading' (duration: 236.323312ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:19:06.082495Z","caller":"traceutil/trace.go:171","msg":"trace[10648339] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"105.454658ms","start":"2026-08-11T18:19:05.977016Z","end":"2026-08-11T18:19:06.082471Z","steps":["trace[10648339] 'process raft request' (duration: 104.189703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:06.082716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.89218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"info","ts":"2026-08-11T18:19:06.082853Z","caller":"traceutil/trace.go:171","msg":"trace[2110626320] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4806; }","duration":"106.066854ms","start":"2026-08-11T18:19:05.976765Z","end":"2026-08-11T18:19:06.082832Z","steps":["trace[2110626320] 'agreement among raft nodes before linearized reading' (duration: 105.760736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:06.085731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.158639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-08-11T18:19:06.085774Z","caller":"traceutil/trace.go:171","msg":"trace[701823201] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4806; }","duration":"145.503019ms","start":"2026-08-11T18:19:05.940259Z","end":"2026-08-11T18:19:06.085762Z","steps":["trace[701823201] 'agreement among raft nodes before linearized reading' (duration: 142.042936ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:19:06.206198Z","caller":"traceutil/trace.go:171","msg":"trace[1615250689] linearizableReadLoop","detail":"{readStateIndex:4985; appliedIndex:4983; }","duration":"114.45441ms","start":"2026-08-11T18:19:06.091718Z","end":"2026-08-11T18:19:06.206172Z","steps":["trace[1615250689] 'read index received' (duration: 55.19698ms)","trace[1615250689] 'applied index is now lower than readState.Index' (duration: 59.25393ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:19:06.206259Z","caller":"traceutil/trace.go:171","msg":"trace[2110552343] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"111.84995ms","start":"2026-08-11T18:19:06.094366Z","end":"2026-08-11T18:19:06.206216Z","steps":["trace[2110552343] 'process raft request' (duration: 111.653545ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:19:06.20631Z","caller":"traceutil/trace.go:171","msg":"trace[1675221299] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"114.752679ms","start":"2026-08-11T18:19:06.091504Z","end":"2026-08-11T18:19:06.206257Z","steps":["trace[1675221299] 'process raft request' (duration: 108.896791ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:19:06.206486Z","caller":"traceutil/trace.go:171","msg":"trace[1748618986] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"106.143326ms","start":"2026-08-11T18:19:06.100306Z","end":"2026-08-11T18:19:06.20645Z","steps":["trace[1748618986] 'process raft request' (duration: 105.761605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:06.206543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.632235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-11T18:19:06.206592Z","caller":"traceutil/trace.go:171","msg":"trace[871027869] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4810; }","duration":"114.705697ms","start":"2026-08-11T18:19:06.091872Z","end":"2026-08-11T18:19:06.206577Z","steps":["trace[871027869] 'agreement among raft nodes before linearized reading' (duration: 114.4518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:06.206599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.733024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11279"} {"level":"warn","ts":"2026-08-11T18:19:06.206661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.703073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-08-11T18:19:06.206651Z","caller":"traceutil/trace.go:171","msg":"trace[88661701] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4810; }","duration":"112.821476ms","start":"2026-08-11T18:19:06.093817Z","end":"2026-08-11T18:19:06.206638Z","steps":["trace[88661701] 'agreement among raft nodes before linearized reading' (duration: 112.668212ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:19:06.206717Z","caller":"traceutil/trace.go:171","msg":"trace[692307491] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4810; }","duration":"112.817706ms","start":"2026-08-11T18:19:06.093879Z","end":"2026-08-11T18:19:06.206697Z","steps":["trace[692307491] 'agreement among raft nodes before linearized reading' (duration: 112.56509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:06.206577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.8243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16086"} {"level":"info","ts":"2026-08-11T18:19:06.206906Z","caller":"traceutil/trace.go:171","msg":"trace[103593579] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4810; }","duration":"115.21694ms","start":"2026-08-11T18:19:06.091667Z","end":"2026-08-11T18:19:06.206884Z","steps":["trace[103593579] 'agreement among raft nodes before linearized reading' (duration: 114.644225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:06.206894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.776189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-08-11T18:19:06.207001Z","caller":"traceutil/trace.go:171","msg":"trace[1102906658] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4810; }","duration":"114.921743ms","start":"2026-08-11T18:19:06.092053Z","end":"2026-08-11T18:19:06.206975Z","steps":["trace[1102906658] 'agreement among raft nodes before linearized reading' (duration: 114.513212ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:19.121087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.753972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T18:19:19.121178Z","caller":"traceutil/trace.go:171","msg":"trace[254014215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5003; }","duration":"383.898575ms","start":"2026-08-11T18:19:18.737259Z","end":"2026-08-11T18:19:19.121158Z","steps":["trace[254014215] 'range keys from in-memory index tree' (duration: 383.671139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T18:19:19.121225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T18:19:18.737234Z","time spent":"383.975748ms","remote":"127.0.0.1:60974","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-08-11T18:19:19.125178Z","caller":"traceutil/trace.go:171","msg":"trace[732057405] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"183.589343ms","start":"2026-08-11T18:19:18.941577Z","end":"2026-08-11T18:19:19.125167Z","steps":["trace[732057405] 'process raft request' (duration: 183.406958ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:19:19.942547Z","caller":"traceutil/trace.go:171","msg":"trace[1894188517] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"119.447089ms","start":"2026-08-11T18:19:19.823063Z","end":"2026-08-11T18:19:19.94251Z","steps":["trace[1894188517] 'process raft request' (duration: 93.964734ms)","trace[1894188517] 'compare' (duration: 25.200497ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:19:19.942788Z","caller":"traceutil/trace.go:171","msg":"trace[656800901] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"118.083082ms","start":"2026-08-11T18:19:19.824675Z","end":"2026-08-11T18:19:19.942759Z","steps":["trace[656800901] 'process raft request' (duration: 117.720223ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:20:31.029093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2919} {"level":"info","ts":"2026-08-11T18:20:31.15887Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2919,"took":"128.231265ms","hash":3611842569,"current-db-size-bytes":54042624,"current-db-size":"54 MB","current-db-size-in-use-bytes":29310976,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-11T18:20:31.158932Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3611842569,"revision":2919,"compact-revision":-1} {"level":"info","ts":"2026-08-11T18:24:31.908978Z","caller":"traceutil/trace.go:171","msg":"trace[1015908453] transaction","detail":"{read_only:false; response_revision:7148; number_of_response:1; }","duration":"103.62202ms","start":"2026-08-11T18:24:31.805311Z","end":"2026-08-11T18:24:31.908933Z","steps":["trace[1015908453] 'process raft request' (duration: 103.465167ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T18:25:31.177541Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5600} {"level":"info","ts":"2026-08-11T18:25:31.177684Z","caller":"traceutil/trace.go:171","msg":"trace[1183846116] compact","detail":"{revision:5600; response_revision:7449; }","duration":"116.434403ms","start":"2026-08-11T18:25:31.061215Z","end":"2026-08-11T18:25:31.17765Z","steps":["trace[1183846116] 'process raft request' (duration: 33.748413ms)","trace[1183846116] 'check and update compact revision' (duration: 82.396094ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T18:25:31.373553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5600,"took":"194.050969ms","hash":2863937507,"current-db-size-bytes":54042624,"current-db-size":"54 MB","current-db-size-in-use-bytes":18497536,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-11T18:25:31.37363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2863937507,"revision":5600,"compact-revision":2919}