{"level":"warn","ts":"2026-10-06T09:59:06.270616Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-06T09:59:06.270767Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.153:2380","--initial-cluster=instance=https://162.253.55.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.153:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-06T09:59:06.270879Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-06T09:59:06.2709Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.153:2380"]} {"level":"info","ts":"2026-10-06T09:59:06.270929Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-06T09:59:06.271836Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.153:2379"]} {"level":"info","ts":"2026-10-06T09:59:06.27194Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.153:2380"],"listen-peer-urls":["https://162.253.55.153:2380"],"advertise-client-urls":["https://162.253.55.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.153:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-06T09:59:06.282534Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.249088ms"} {"level":"info","ts":"2026-10-06T09:59:06.291298Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"522eb07aa35b671b","cluster-id":"49de17121e7ddda"} {"level":"info","ts":"2026-10-06T09:59:06.291406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b switched to configuration voters=()"} {"level":"info","ts":"2026-10-06T09:59:06.291449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became follower at term 0"} {"level":"info","ts":"2026-10-06T09:59:06.291476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 522eb07aa35b671b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-06T09:59:06.291495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became follower at term 1"} {"level":"info","ts":"2026-10-06T09:59:06.291558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b switched to configuration voters=(5921864600811955995)"} {"level":"warn","ts":"2026-10-06T09:59:06.30011Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-06T09:59:06.302198Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-06T09:59:06.303269Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-06T09:59:06.305268Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"522eb07aa35b671b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-06T09:59:06.305455Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"522eb07aa35b671b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-06T09:59:06.305602Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-06T09:59:06.305664Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-06T09:59:06.305672Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-06T09:59:06.307269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b switched to configuration voters=(5921864600811955995)"} {"level":"info","ts":"2026-10-06T09:59:06.307462Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49de17121e7ddda","local-member-id":"522eb07aa35b671b","added-peer-id":"522eb07aa35b671b","added-peer-peer-urls":["https://162.253.55.153:2380"]} {"level":"info","ts":"2026-10-06T09:59:06.311659Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-06T09:59:06.311811Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.153:2380"} {"level":"info","ts":"2026-10-06T09:59:06.311873Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.153:2380"} {"level":"info","ts":"2026-10-06T09:59:06.312001Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"522eb07aa35b671b","initial-advertise-peer-urls":["https://162.253.55.153:2380"],"listen-peer-urls":["https://162.253.55.153:2380"],"advertise-client-urls":["https://162.253.55.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-06T09:59:06.312041Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-06T09:59:07.09192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b is starting a new election at term 1"} {"level":"info","ts":"2026-10-06T09:59:07.091973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-06T09:59:07.091998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b received MsgPreVoteResp from 522eb07aa35b671b at term 1"} {"level":"info","ts":"2026-10-06T09:59:07.092017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became candidate at term 2"} {"level":"info","ts":"2026-10-06T09:59:07.092022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b received MsgVoteResp from 522eb07aa35b671b at term 2"} {"level":"info","ts":"2026-10-06T09:59:07.09203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became leader at term 2"} {"level":"info","ts":"2026-10-06T09:59:07.092037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 522eb07aa35b671b elected leader 522eb07aa35b671b at term 2"} {"level":"info","ts":"2026-10-06T09:59:07.116419Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T09:59:07.11909Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T09:59:07.119101Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"522eb07aa35b671b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.153:2379]}","request-path":"/0/members/522eb07aa35b671b/attributes","cluster-id":"49de17121e7ddda","publish-timeout":"7s"} {"level":"info","ts":"2026-10-06T09:59:07.119227Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T09:59:07.119652Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-06T09:59:07.119733Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-06T09:59:07.1208Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-06T09:59:07.122183Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.153:2379"} {"level":"info","ts":"2026-10-06T09:59:07.125469Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49de17121e7ddda","local-member-id":"522eb07aa35b671b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T09:59:07.125581Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T09:59:07.125613Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T09:59:08.799623Z","caller":"traceutil/trace.go:171","msg":"trace[1240082859] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"138.822555ms","start":"2026-10-06T09:59:08.660778Z","end":"2026-10-06T09:59:08.799601Z","steps":["trace[1240082859] 'process raft request' (duration: 138.610882ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:09.016672Z","caller":"traceutil/trace.go:171","msg":"trace[378391272] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"355.609969ms","start":"2026-10-06T09:59:08.66104Z","end":"2026-10-06T09:59:09.01665Z","steps":["trace[378391272] 'process raft request' (duration: 355.363575ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:09.016667Z","caller":"traceutil/trace.go:171","msg":"trace[1318751552] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"355.171463ms","start":"2026-10-06T09:59:08.661463Z","end":"2026-10-06T09:59:09.016634Z","steps":["trace[1318751552] 'process raft request' (duration: 355.054161ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:09.016904Z","caller":"traceutil/trace.go:171","msg":"trace[1055573894] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"280.221808ms","start":"2026-10-06T09:59:08.736668Z","end":"2026-10-06T09:59:09.01689Z","steps":["trace[1055573894] 'process raft request' (duration: 279.906534ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:09.017066Z","caller":"traceutil/trace.go:171","msg":"trace[1426301506] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"280.302201ms","start":"2026-10-06T09:59:08.736462Z","end":"2026-10-06T09:59:09.016764Z","steps":["trace[1426301506] 'process raft request' (duration: 280.086447ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:09.01709Z","caller":"traceutil/trace.go:171","msg":"trace[1335449891] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"280.164908ms","start":"2026-10-06T09:59:08.73688Z","end":"2026-10-06T09:59:09.017045Z","steps":["trace[1335449891] 'process raft request' (duration: 279.717131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:59:09.017283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:59:08.661455Z","time spent":"355.402896ms","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-06T09:59:09.017368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:59:08.661033Z","time spent":"355.7101ms","remote":"127.0.0.1:40654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T09:59:09.017766Z","caller":"traceutil/trace.go:171","msg":"trace[2111905032] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"280.668025ms","start":"2026-10-06T09:59:08.737084Z","end":"2026-10-06T09:59:09.017752Z","steps":["trace[2111905032] 'process raft request' (duration: 279.532588ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:09.017822Z","caller":"traceutil/trace.go:171","msg":"trace[606628380] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"356.636104ms","start":"2026-10-06T09:59:08.661168Z","end":"2026-10-06T09:59:09.017805Z","steps":["trace[606628380] 'read index received' (duration: 138.270246ms)","trace[606628380] 'applied index is now lower than readState.Index' (duration: 218.365098ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T09:59:09.018084Z","caller":"traceutil/trace.go:171","msg":"trace[417117999] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"280.876459ms","start":"2026-10-06T09:59:08.737197Z","end":"2026-10-06T09:59:09.018074Z","steps":["trace[417117999] 'process raft request' (duration: 280.520004ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:09.018219Z","caller":"traceutil/trace.go:171","msg":"trace[1121342101] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"280.894859ms","start":"2026-10-06T09:59:08.737316Z","end":"2026-10-06T09:59:09.018211Z","steps":["trace[1121342101] 'process raft request' (duration: 280.429812ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:09.018432Z","caller":"traceutil/trace.go:171","msg":"trace[282722520] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"273.718708ms","start":"2026-10-06T09:59:08.744702Z","end":"2026-10-06T09:59:09.018421Z","steps":["trace[282722520] 'process raft request' (duration: 273.070699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:59:09.018645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.467427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-06T09:59:09.018736Z","caller":"traceutil/trace.go:171","msg":"trace[1330949687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"357.578458ms","start":"2026-10-06T09:59:08.661147Z","end":"2026-10-06T09:59:09.018725Z","steps":["trace[1330949687] 'agreement among raft nodes before linearized reading' (duration: 357.425456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:59:09.018786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:59:08.661135Z","time spent":"357.620719ms","remote":"127.0.0.1:40858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-06T09:59:09.019056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.849114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-06T09:59:09.019107Z","caller":"traceutil/trace.go:171","msg":"trace[1830322222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"357.923795ms","start":"2026-10-06T09:59:08.661172Z","end":"2026-10-06T09:59:09.019096Z","steps":["trace[1830322222] 'agreement among raft nodes before linearized reading' (duration: 357.849324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:59:09.019135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:59:08.661156Z","time spent":"357.972155ms","remote":"127.0.0.1:40858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-06T09:59:09.019192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.741414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-06T09:59:09.019243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.458835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-06T09:59:09.019272Z","caller":"traceutil/trace.go:171","msg":"trace[1737776804] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"284.519455ms","start":"2026-10-06T09:59:08.734745Z","end":"2026-10-06T09:59:09.019264Z","steps":["trace[1737776804] 'agreement among raft nodes before linearized reading' (duration: 284.485235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:59:09.019396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.208251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.153\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-06T09:59:09.019427Z","caller":"traceutil/trace.go:171","msg":"trace[1536608356] range","detail":"{range_begin:/registry/masterleases/162.253.55.153; range_end:; response_count:0; response_revision:11; }","duration":"286.269862ms","start":"2026-10-06T09:59:08.73315Z","end":"2026-10-06T09:59:09.01942Z","steps":["trace[1536608356] 'agreement among raft nodes before linearized reading' (duration: 286.227371ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:09.019519Z","caller":"traceutil/trace.go:171","msg":"trace[1782636315] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"231.927687ms","start":"2026-10-06T09:59:08.78733Z","end":"2026-10-06T09:59:09.019257Z","steps":["trace[1782636315] 'agreement among raft nodes before linearized reading' (duration: 231.714204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:59:09.019648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.380293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-06T09:59:09.019684Z","caller":"traceutil/trace.go:171","msg":"trace[1689292509] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"349.448544ms","start":"2026-10-06T09:59:08.670227Z","end":"2026-10-06T09:59:09.019675Z","steps":["trace[1689292509] 'agreement among raft nodes before linearized reading' (duration: 349.396914ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:59:09.019708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:59:08.670205Z","time spent":"349.497975ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-10-06T09:59:09.019921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.615649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-06T09:59:09.020039Z","caller":"traceutil/trace.go:171","msg":"trace[1148538944] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"208.749891ms","start":"2026-10-06T09:59:08.811274Z","end":"2026-10-06T09:59:09.020024Z","steps":["trace[1148538944] 'agreement among raft nodes before linearized reading' (duration: 208.611518ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:59:09.01921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.119648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-06T09:59:09.02019Z","caller":"traceutil/trace.go:171","msg":"trace[1563672094] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"283.125434ms","start":"2026-10-06T09:59:08.737053Z","end":"2026-10-06T09:59:09.020178Z","steps":["trace[1563672094] 'agreement among raft nodes before linearized reading' (duration: 282.137159ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:47.736891Z","caller":"traceutil/trace.go:171","msg":"trace[1694090100] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"112.447638ms","start":"2026-10-06T09:59:47.624411Z","end":"2026-10-06T09:59:47.736859Z","steps":["trace[1694090100] 'process raft request' (duration: 112.254646ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:49.391462Z","caller":"traceutil/trace.go:171","msg":"trace[768842757] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"129.251791ms","start":"2026-10-06T09:59:49.26218Z","end":"2026-10-06T09:59:49.391432Z","steps":["trace[768842757] 'process raft request' (duration: 129.022748ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:49.517472Z","caller":"traceutil/trace.go:171","msg":"trace[411817903] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"148.036707ms","start":"2026-10-06T09:59:49.369398Z","end":"2026-10-06T09:59:49.517435Z","steps":["trace[411817903] 'process raft request' (duration: 146.316363ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:49.859086Z","caller":"traceutil/trace.go:171","msg":"trace[2015945461] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"116.150425ms","start":"2026-10-06T09:59:49.742887Z","end":"2026-10-06T09:59:49.859038Z","steps":["trace[2015945461] 'read index received' (duration: 100.797458ms)","trace[2015945461] 'applied index is now lower than readState.Index' (duration: 15.352307ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T09:59:49.859373Z","caller":"traceutil/trace.go:171","msg":"trace[1398163918] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"259.633898ms","start":"2026-10-06T09:59:49.599571Z","end":"2026-10-06T09:59:49.859205Z","steps":["trace[1398163918] 'process raft request' (duration: 244.20421ms)","trace[1398163918] 'compare' (duration: 14.762019ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T09:59:49.859549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.636052ms","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-10-06T09:59:49.859631Z","caller":"traceutil/trace.go:171","msg":"trace[533200086] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"116.744603ms","start":"2026-10-06T09:59:49.742861Z","end":"2026-10-06T09:59:49.859605Z","steps":["trace[533200086] 'agreement among raft nodes before linearized reading' (duration: 116.363608ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:51.608147Z","caller":"traceutil/trace.go:171","msg":"trace[1930594247] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"174.279653ms","start":"2026-10-06T09:59:51.433844Z","end":"2026-10-06T09:59:51.608123Z","steps":["trace[1930594247] 'process raft request' (duration: 174.022089ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:51.608448Z","caller":"traceutil/trace.go:171","msg":"trace[391275433] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"173.695493ms","start":"2026-10-06T09:59:51.434734Z","end":"2026-10-06T09:59:51.60843Z","steps":["trace[391275433] 'process raft request' (duration: 173.275417ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:00:54.536442Z","caller":"traceutil/trace.go:171","msg":"trace[1364169282] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"127.521827ms","start":"2026-10-06T10:00:54.408657Z","end":"2026-10-06T10:00:54.536179Z","steps":["trace[1364169282] 'process raft request' (duration: 127.373685ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:01:27.424233Z","caller":"traceutil/trace.go:171","msg":"trace[1152077974] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"102.429338ms","start":"2026-10-06T10:01:27.321758Z","end":"2026-10-06T10:01:27.424187Z","steps":["trace[1152077974] 'process raft request' (duration: 102.201824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:01:27.752439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.367002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:01:27.75255Z","caller":"traceutil/trace.go:171","msg":"trace[1889669431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1854; }","duration":"181.533306ms","start":"2026-10-06T10:01:27.570991Z","end":"2026-10-06T10:01:27.752524Z","steps":["trace[1889669431] 'range keys from in-memory index tree' (duration: 181.24146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:01:27.752625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.522635ms","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-10-06T10:01:27.752719Z","caller":"traceutil/trace.go:171","msg":"trace[141898375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1854; }","duration":"128.662548ms","start":"2026-10-06T10:01:27.624024Z","end":"2026-10-06T10:01:27.752686Z","steps":["trace[141898375] 'range keys from in-memory index tree' (duration: 128.222669ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:01:28.171889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.694852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-06T10:01:28.171991Z","caller":"traceutil/trace.go:171","msg":"trace[1176918220] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"276.312619ms","start":"2026-10-06T10:01:27.89566Z","end":"2026-10-06T10:01:28.171973Z","steps":["trace[1176918220] 'read index received' (duration: 92.209288ms)","trace[1176918220] 'applied index is now lower than readState.Index' (duration: 184.102081ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:01:28.172216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.858897ms","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-10-06T10:01:28.17225Z","caller":"traceutil/trace.go:171","msg":"trace[198052462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1856; }","duration":"132.940609ms","start":"2026-10-06T10:01:28.0393Z","end":"2026-10-06T10:01:28.172241Z","steps":["trace[198052462] 'agreement among raft nodes before linearized reading' (duration: 132.767175ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:01:28.172361Z","caller":"traceutil/trace.go:171","msg":"trace[1101988634] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"338.561051ms","start":"2026-10-06T10:01:27.83377Z","end":"2026-10-06T10:01:28.172331Z","steps":["trace[1101988634] 'process raft request' (duration: 154.171584ms)","trace[1101988634] 'compare' (duration: 183.57364ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:01:28.172572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:01:27.833737Z","time spent":"338.776925ms","remote":"127.0.0.1:40776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3726,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:01:28.172406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.711537ms","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-10-06T10:01:28.172735Z","caller":"traceutil/trace.go:171","msg":"trace[1084586016] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1856; }","duration":"277.089706ms","start":"2026-10-06T10:01:27.895586Z","end":"2026-10-06T10:01:28.172676Z","steps":["trace[1084586016] 'agreement among raft nodes before linearized reading' (duration: 276.477993ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:01:40.436525Z","caller":"traceutil/trace.go:171","msg":"trace[93211125] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"127.935198ms","start":"2026-10-06T10:01:40.308565Z","end":"2026-10-06T10:01:40.4365Z","steps":["trace[93211125] 'process raft request' (duration: 120.45949ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:01:40.769544Z","caller":"traceutil/trace.go:171","msg":"trace[1072331909] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"111.93729ms","start":"2026-10-06T10:01:40.657331Z","end":"2026-10-06T10:01:40.769269Z","steps":["trace[1072331909] 'process raft request' (duration: 22.868836ms)","trace[1072331909] 'compare' (duration: 88.800559ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:02:07.205568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.707192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-06T10:02:07.205717Z","caller":"traceutil/trace.go:171","msg":"trace[1452098097] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2121; }","duration":"113.934855ms","start":"2026-10-06T10:02:07.091751Z","end":"2026-10-06T10:02:07.205685Z","steps":["trace[1452098097] 'range keys from in-memory index tree' (duration: 113.382125ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:03:07.396202Z","caller":"traceutil/trace.go:171","msg":"trace[1980698825] transaction","detail":"{read_only:false; response_revision:2550; number_of_response:1; }","duration":"152.016553ms","start":"2026-10-06T10:03:07.244154Z","end":"2026-10-06T10:03:07.396171Z","steps":["trace[1980698825] 'process raft request' (duration: 150.07054ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:03:39.220226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.501051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-06T10:03:39.220528Z","caller":"traceutil/trace.go:171","msg":"trace[1843851726] transaction","detail":"{read_only:false; response_revision:2723; number_of_response:1; }","duration":"231.017926ms","start":"2026-10-06T10:03:38.989486Z","end":"2026-10-06T10:03:39.220503Z","steps":["trace[1843851726] 'process raft request' (duration: 103.125118ms)","trace[1843851726] 'compare' (duration: 127.107495ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:03:39.468384Z","caller":"traceutil/trace.go:171","msg":"trace[530831516] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"183.07629ms","start":"2026-10-06T10:03:39.285269Z","end":"2026-10-06T10:03:39.468345Z","steps":["trace[530831516] 'process raft request' (duration: 173.289712ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:07:55.8434Z","caller":"traceutil/trace.go:171","msg":"trace[1407419599] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4723; }","duration":"131.270337ms","start":"2026-10-06T10:07:55.712087Z","end":"2026-10-06T10:07:55.843357Z","steps":["trace[1407419599] 'process raft request' (duration: 111.60485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:07:55.843556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.922744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:07:55.843427Z","caller":"traceutil/trace.go:171","msg":"trace[189821428] linearizableReadLoop","detail":"{readStateIndex:4901; appliedIndex:4900; }","duration":"106.793093ms","start":"2026-10-06T10:07:55.736606Z","end":"2026-10-06T10:07:55.843399Z","steps":["trace[189821428] 'read index received' (duration: 87.156976ms)","trace[189821428] 'applied index is now lower than readState.Index' (duration: 19.632997ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:07:55.843609Z","caller":"traceutil/trace.go:171","msg":"trace[1162596782] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4724; }","duration":"107.023655ms","start":"2026-10-06T10:07:55.736567Z","end":"2026-10-06T10:07:55.843591Z","steps":["trace[1162596782] 'agreement among raft nodes before linearized reading' (duration: 106.908134ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:05.694754Z","caller":"traceutil/trace.go:171","msg":"trace[1187933281] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"113.524834ms","start":"2026-10-06T10:08:05.581195Z","end":"2026-10-06T10:08:05.69472Z","steps":["trace[1187933281] 'process raft request' (duration: 113.19106ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:06.027547Z","caller":"traceutil/trace.go:171","msg":"trace[1395870884] linearizableReadLoop","detail":"{readStateIndex:5119; appliedIndex:5118; }","duration":"194.511092ms","start":"2026-10-06T10:08:05.833013Z","end":"2026-10-06T10:08:06.027524Z","steps":["trace[1395870884] 'read index received' (duration: 124.61528ms)","trace[1395870884] 'applied index is now lower than readState.Index' (duration: 69.894782ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:08:06.027602Z","caller":"traceutil/trace.go:171","msg":"trace[716773211] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"195.09466ms","start":"2026-10-06T10:08:05.832479Z","end":"2026-10-06T10:08:06.027574Z","steps":["trace[716773211] 'process raft request' (duration: 125.752596ms)","trace[716773211] 'compare' (duration: 69.164981ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:08:06.053943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.900812ms","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-10-06T10:08:06.054038Z","caller":"traceutil/trace.go:171","msg":"trace[778587371] 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:4935; }","duration":"221.029345ms","start":"2026-10-06T10:08:05.83298Z","end":"2026-10-06T10:08:06.054009Z","steps":["trace[778587371] 'agreement among raft nodes before linearized reading' (duration: 194.645975ms)","trace[778587371] 'range keys from in-memory index tree' (duration: 26.178047ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:08:06.187476Z","caller":"traceutil/trace.go:171","msg":"trace[616070524] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"152.230058ms","start":"2026-10-06T10:08:06.035204Z","end":"2026-10-06T10:08:06.187434Z","steps":["trace[616070524] 'process raft request' (duration: 142.370119ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:06.187494Z","caller":"traceutil/trace.go:171","msg":"trace[880090789] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"152.029835ms","start":"2026-10-06T10:08:06.035413Z","end":"2026-10-06T10:08:06.187443Z","steps":["trace[880090789] 'process raft request' (duration: 151.789061ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:06.187532Z","caller":"traceutil/trace.go:171","msg":"trace[1945383009] linearizableReadLoop","detail":"{readStateIndex:5125; appliedIndex:5121; }","duration":"147.808286ms","start":"2026-10-06T10:08:06.039599Z","end":"2026-10-06T10:08:06.187408Z","steps":["trace[1945383009] 'read index received' (duration: 64.730899ms)","trace[1945383009] 'applied index is now lower than readState.Index' (duration: 83.056517ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:08:06.187708Z","caller":"traceutil/trace.go:171","msg":"trace[2133933164] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"151.805282ms","start":"2026-10-06T10:08:06.035782Z","end":"2026-10-06T10:08:06.187587Z","steps":["trace[2133933164] 'process raft request' (duration: 151.481017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:08:06.18788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.258532ms","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-10-06T10:08:06.187917Z","caller":"traceutil/trace.go:171","msg":"trace[1468587339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4940; }","duration":"148.352963ms","start":"2026-10-06T10:08:06.039553Z","end":"2026-10-06T10:08:06.187906Z","steps":["trace[1468587339] 'agreement among raft nodes before linearized reading' (duration: 148.203651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:08:06.187987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.701116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5f968fff-fae1-4d6d-ad74-fb0fe5ed1722\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-10-06T10:08:06.18805Z","caller":"traceutil/trace.go:171","msg":"trace[1510697980] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5f968fff-fae1-4d6d-ad74-fb0fe5ed1722; range_end:; response_count:1; response_revision:4940; }","duration":"130.811547ms","start":"2026-10-06T10:08:06.05722Z","end":"2026-10-06T10:08:06.188032Z","steps":["trace[1510697980] 'agreement among raft nodes before linearized reading' (duration: 130.621444ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:18.258188Z","caller":"traceutil/trace.go:171","msg":"trace[916913974] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"159.638657ms","start":"2026-10-06T10:08:18.098525Z","end":"2026-10-06T10:08:18.258164Z","steps":["trace[916913974] 'process raft request' (duration: 133.624422ms)","trace[916913974] 'compare' (duration: 25.593959ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:08:19.00072Z","caller":"traceutil/trace.go:171","msg":"trace[972721847] linearizableReadLoop","detail":"{readStateIndex:5292; appliedIndex:5291; }","duration":"129.879791ms","start":"2026-10-06T10:08:18.870818Z","end":"2026-10-06T10:08:19.000698Z","steps":["trace[972721847] 'read index received' (duration: 129.684478ms)","trace[972721847] 'applied index is now lower than readState.Index' (duration: 194.333µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:08:19.000784Z","caller":"traceutil/trace.go:171","msg":"trace[345800410] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"131.818727ms","start":"2026-10-06T10:08:18.868937Z","end":"2026-10-06T10:08:19.000756Z","steps":["trace[345800410] 'process raft request' (duration: 131.631225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:08:19.001134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.295106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-10-06T10:08:19.001268Z","caller":"traceutil/trace.go:171","msg":"trace[202287762] 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:5103; }","duration":"130.354287ms","start":"2026-10-06T10:08:18.870812Z","end":"2026-10-06T10:08:19.001166Z","steps":["trace[202287762] 'agreement among raft nodes before linearized reading' (duration: 129.972702ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:19.118465Z","caller":"traceutil/trace.go:171","msg":"trace[987471918] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"110.050632ms","start":"2026-10-06T10:08:19.008385Z","end":"2026-10-06T10:08:19.118436Z","steps":["trace[987471918] 'process raft request' (duration: 109.858459ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:19.118882Z","caller":"traceutil/trace.go:171","msg":"trace[1344089890] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"110.257184ms","start":"2026-10-06T10:08:19.008599Z","end":"2026-10-06T10:08:19.118856Z","steps":["trace[1344089890] 'process raft request' (duration: 109.772188ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:19.119197Z","caller":"traceutil/trace.go:171","msg":"trace[52805512] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"109.991221ms","start":"2026-10-06T10:08:19.009176Z","end":"2026-10-06T10:08:19.119167Z","steps":["trace[52805512] 'process raft request' (duration: 109.461383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:08:19.119059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.575509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-03cd1701-dfd3-474e-a17b-cc8a37ca771c\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-10-06T10:08:19.119627Z","caller":"traceutil/trace.go:171","msg":"trace[1628837184] range","detail":"{range_begin:/registry/persistentvolumes/pvc-03cd1701-dfd3-474e-a17b-cc8a37ca771c; range_end:; response_count:1; response_revision:5107; }","duration":"111.152787ms","start":"2026-10-06T10:08:19.00843Z","end":"2026-10-06T10:08:19.119583Z","steps":["trace[1628837184] 'agreement among raft nodes before linearized reading' (duration: 110.440768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:08:19.374331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.148981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-06T10:08:19.374526Z","caller":"traceutil/trace.go:171","msg":"trace[419761663] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"289.78612ms","start":"2026-10-06T10:08:19.08471Z","end":"2026-10-06T10:08:19.374496Z","steps":["trace[419761663] 'process raft request' (duration: 156.939249ms)","trace[419761663] 'compare' (duration: 131.575844ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:08:19.374829Z","caller":"traceutil/trace.go:171","msg":"trace[909291186] linearizableReadLoop","detail":"{readStateIndex:5297; appliedIndex:5296; }","duration":"250.599961ms","start":"2026-10-06T10:08:19.123829Z","end":"2026-10-06T10:08:19.374429Z","steps":["trace[909291186] 'read index received' (duration: 117.72784ms)","trace[909291186] 'applied index is now lower than readState.Index' (duration: 132.870321ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:08:19.375016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.177329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-10-06T10:08:19.375405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.488715ms","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-10-06T10:08:19.375473Z","caller":"traceutil/trace.go:171","msg":"trace[557046143] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5108; }","duration":"204.588837ms","start":"2026-10-06T10:08:19.170863Z","end":"2026-10-06T10:08:19.375452Z","steps":["trace[557046143] 'agreement among raft nodes before linearized reading' (duration: 204.362133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:08:19.375651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.314773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-06T10:08:19.375778Z","caller":"traceutil/trace.go:171","msg":"trace[490895373] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5108; }","duration":"104.411663ms","start":"2026-10-06T10:08:19.271286Z","end":"2026-10-06T10:08:19.375698Z","steps":["trace[490895373] 'agreement among raft nodes before linearized reading' (duration: 104.242441ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:19.37612Z","caller":"traceutil/trace.go:171","msg":"trace[473812997] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5108; }","duration":"251.340581ms","start":"2026-10-06T10:08:19.123786Z","end":"2026-10-06T10:08:19.375127Z","steps":["trace[473812997] 'agreement among raft nodes before linearized reading' (duration: 250.879094ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:19.54768Z","caller":"traceutil/trace.go:171","msg":"trace[234290483] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"162.217514ms","start":"2026-10-06T10:08:19.385433Z","end":"2026-10-06T10:08:19.54765Z","steps":["trace[234290483] 'process raft request' (duration: 161.95413ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:19.548174Z","caller":"traceutil/trace.go:171","msg":"trace[373415347] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"162.608058ms","start":"2026-10-06T10:08:19.385543Z","end":"2026-10-06T10:08:19.548151Z","steps":["trace[373415347] 'process raft request' (duration: 161.910049ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:19.548218Z","caller":"traceutil/trace.go:171","msg":"trace[469855571] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"149.955001ms","start":"2026-10-06T10:08:19.398239Z","end":"2026-10-06T10:08:19.548194Z","steps":["trace[469855571] 'process raft request' (duration: 149.278502ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:19.548625Z","caller":"traceutil/trace.go:171","msg":"trace[957165451] transaction","detail":"{read_only:false; response_revision:5111; number_of_response:1; }","duration":"163.210477ms","start":"2026-10-06T10:08:19.385398Z","end":"2026-10-06T10:08:19.548608Z","steps":["trace[957165451] 'process raft request' (duration: 161.909009ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:19.54868Z","caller":"traceutil/trace.go:171","msg":"trace[600781301] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"163.925116ms","start":"2026-10-06T10:08:19.384738Z","end":"2026-10-06T10:08:19.548663Z","steps":["trace[600781301] 'process raft request' (duration: 122.929412ms)","trace[600781301] 'compare' (duration: 39.501314ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:08:59.032673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.51385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-06T10:08:59.03297Z","caller":"traceutil/trace.go:171","msg":"trace[1764793011] linearizableReadLoop","detail":"{readStateIndex:5655; appliedIndex:5654; }","duration":"119.804521ms","start":"2026-10-06T10:08:58.913133Z","end":"2026-10-06T10:08:59.032938Z","steps":["trace[1764793011] 'read index received' (duration: 51.901µs)","trace[1764793011] 'applied index is now lower than readState.Index' (duration: 119.74905ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:08:59.033083Z","caller":"traceutil/trace.go:171","msg":"trace[166238886] transaction","detail":"{read_only:false; response_revision:5457; number_of_response:1; }","duration":"182.252841ms","start":"2026-10-06T10:08:58.850805Z","end":"2026-10-06T10:08:59.033058Z","steps":["trace[166238886] 'process raft request' (duration: 51.244434ms)","trace[166238886] 'compare' (duration: 130.383728ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:08:59.033274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.135575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-06T10:08:59.033379Z","caller":"traceutil/trace.go:171","msg":"trace[1883423472] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:5457; }","duration":"120.287867ms","start":"2026-10-06T10:08:58.913072Z","end":"2026-10-06T10:08:59.03336Z","steps":["trace[1883423472] 'agreement among raft nodes before linearized reading' (duration: 120.100424ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:08:59.259584Z","caller":"traceutil/trace.go:171","msg":"trace[1997262247] linearizableReadLoop","detail":"{readStateIndex:5656; appliedIndex:5655; }","duration":"128.457941ms","start":"2026-10-06T10:08:59.131102Z","end":"2026-10-06T10:08:59.25956Z","steps":["trace[1997262247] 'read index received' (duration: 128.333769ms)","trace[1997262247] 'applied index is now lower than readState.Index' (duration: 123.072µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:08:59.259642Z","caller":"traceutil/trace.go:171","msg":"trace[545783716] transaction","detail":"{read_only:false; response_revision:5458; number_of_response:1; }","duration":"129.471414ms","start":"2026-10-06T10:08:59.130134Z","end":"2026-10-06T10:08:59.259605Z","steps":["trace[545783716] 'process raft request' (duration: 129.277012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:08:59.259954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.743795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-10-06T10:08:59.26003Z","caller":"traceutil/trace.go:171","msg":"trace[941735008] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5458; }","duration":"128.933367ms","start":"2026-10-06T10:08:59.131078Z","end":"2026-10-06T10:08:59.260011Z","steps":["trace[941735008] 'agreement among raft nodes before linearized reading' (duration: 128.589512ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:09:07.157383Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2870} {"level":"info","ts":"2026-10-06T10:09:07.274487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2870,"took":"115.828433ms","hash":744409449,"current-db-size-bytes":53522432,"current-db-size":"54 MB","current-db-size-in-use-bytes":28631040,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-06T10:09:07.274554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":744409449,"revision":2870,"compact-revision":-1} {"level":"info","ts":"2026-10-06T10:11:57.15849Z","caller":"traceutil/trace.go:171","msg":"trace[344138643] transaction","detail":"{read_only:false; response_revision:6693; number_of_response:1; }","duration":"124.811823ms","start":"2026-10-06T10:11:57.033648Z","end":"2026-10-06T10:11:57.15846Z","steps":["trace[344138643] 'process raft request' (duration: 124.68127ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:14:07.164176Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5523} {"level":"info","ts":"2026-10-06T10:14:07.302029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5523,"took":"135.999125ms","hash":3322098902,"current-db-size-bytes":53522432,"current-db-size":"54 MB","current-db-size-in-use-bytes":18661376,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-06T10:14:07.302114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3322098902,"revision":5523,"compact-revision":2870}