{"level":"warn","ts":"2026-10-15T18:52:32.836959Z","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-15T18:52:32.837038Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.113: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.113:2380","--initial-cluster=instance=https://162.253.55.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.113: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-15T18:52:32.837092Z","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-15T18:52:32.837102Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-10-15T18:52:32.837117Z","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-15T18:52:32.837735Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"]} {"level":"info","ts":"2026-10-15T18:52:32.837812Z","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.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.113: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-15T18:52:32.843723Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.732876ms"} {"level":"info","ts":"2026-10-15T18:52:32.856162Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3b717bddf98d48e3","cluster-id":"cef0f7c69f0576fc"} {"level":"info","ts":"2026-10-15T18:52:32.856248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T18:52:32.856265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 0"} {"level":"info","ts":"2026-10-15T18:52:32.856285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3b717bddf98d48e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T18:52:32.856295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became follower at term 1"} {"level":"info","ts":"2026-10-15T18:52:32.856326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"warn","ts":"2026-10-15T18:52:32.867684Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T18:52:32.871464Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T18:52:32.872823Z","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-15T18:52:32.877751Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3b717bddf98d48e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T18:52:32.877994Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3b717bddf98d48e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T18:52:32.882935Z","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-15T18:52:32.882972Z","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-15T18:52:32.882982Z","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-15T18:52:32.882968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 switched to configuration voters=(4283340913910827235)"} {"level":"info","ts":"2026-10-15T18:52:32.883438Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","added-peer-id":"3b717bddf98d48e3","added-peer-peer-urls":["https://162.253.55.113:2380"]} {"level":"info","ts":"2026-10-15T18:52:32.88792Z","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-15T18:52:32.888017Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-10-15T18:52:32.888084Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.113:2380"} {"level":"info","ts":"2026-10-15T18:52:32.888276Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3b717bddf98d48e3","initial-advertise-peer-urls":["https://162.253.55.113:2380"],"listen-peer-urls":["https://162.253.55.113:2380"],"advertise-client-urls":["https://162.253.55.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T18:52:32.888316Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T18:52:33.859126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T18:52:33.859213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T18:52:33.859235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgPreVoteResp from 3b717bddf98d48e3 at term 1"} {"level":"info","ts":"2026-10-15T18:52:33.859254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became candidate at term 2"} {"level":"info","ts":"2026-10-15T18:52:33.859264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 received MsgVoteResp from 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-10-15T18:52:33.859278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3b717bddf98d48e3 became leader at term 2"} {"level":"info","ts":"2026-10-15T18:52:33.859291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3b717bddf98d48e3 elected leader 3b717bddf98d48e3 at term 2"} {"level":"info","ts":"2026-10-15T18:52:33.875432Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:52:33.878723Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T18:52:33.878738Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3b717bddf98d48e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.113:2379]}","request-path":"/0/members/3b717bddf98d48e3/attributes","cluster-id":"cef0f7c69f0576fc","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T18:52:33.878763Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T18:52:33.879037Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cef0f7c69f0576fc","local-member-id":"3b717bddf98d48e3","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:52:33.879233Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:52:33.879301Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:52:33.879425Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T18:52:33.879473Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T18:52:33.881326Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.113:2379"} {"level":"info","ts":"2026-10-15T18:52:33.884848Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T18:53:19.86403Z","caller":"traceutil/trace.go:171","msg":"trace[32827076] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"175.835876ms","start":"2026-10-15T18:53:19.688179Z","end":"2026-10-15T18:53:19.864014Z","steps":["trace[32827076] 'process raft request' (duration: 175.799445ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.864101Z","caller":"traceutil/trace.go:171","msg":"trace[786772333] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"178.842784ms","start":"2026-10-15T18:53:19.685225Z","end":"2026-10-15T18:53:19.864067Z","steps":["trace[786772333] 'process raft request' (duration: 178.699281ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.864013Z","caller":"traceutil/trace.go:171","msg":"trace[722791673] transaction","detail":"{read_only:false; number_of_response:1; response_revision:513; }","duration":"181.604958ms","start":"2026-10-15T18:53:19.682382Z","end":"2026-10-15T18:53:19.863987Z","steps":["trace[722791673] 'process raft request' (duration: 181.498046ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.864017Z","caller":"traceutil/trace.go:171","msg":"trace[1160322210] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"182.263413ms","start":"2026-10-15T18:53:19.681726Z","end":"2026-10-15T18:53:19.86399Z","steps":["trace[1160322210] 'process raft request' (duration: 182.088229ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.864101Z","caller":"traceutil/trace.go:171","msg":"trace[1528534243] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"183.273396ms","start":"2026-10-15T18:53:19.680663Z","end":"2026-10-15T18:53:19.863936Z","steps":["trace[1528534243] 'process raft request' (duration: 183.105362ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.863875Z","caller":"traceutil/trace.go:171","msg":"trace[796308403] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"186.138541ms","start":"2026-10-15T18:53:19.677689Z","end":"2026-10-15T18:53:19.863827Z","steps":["trace[796308403] 'process raft request' (duration: 176.868989ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.869411Z","caller":"traceutil/trace.go:171","msg":"trace[1518772188] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"150.405372ms","start":"2026-10-15T18:53:19.71898Z","end":"2026-10-15T18:53:19.869385Z","steps":["trace[1518772188] 'process raft request' (duration: 150.116395ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.869682Z","caller":"traceutil/trace.go:171","msg":"trace[695657189] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"175.546038ms","start":"2026-10-15T18:53:19.694117Z","end":"2026-10-15T18:53:19.869663Z","steps":["trace[695657189] 'process raft request' (duration: 174.29329ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.869805Z","caller":"traceutil/trace.go:171","msg":"trace[1151252246] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"150.898443ms","start":"2026-10-15T18:53:19.718887Z","end":"2026-10-15T18:53:19.869786Z","steps":["trace[1151252246] 'process raft request' (duration: 150.183677ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.869858Z","caller":"traceutil/trace.go:171","msg":"trace[1308583361] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"149.817808ms","start":"2026-10-15T18:53:19.720021Z","end":"2026-10-15T18:53:19.869839Z","steps":["trace[1308583361] 'process raft request' (duration: 149.091862ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.869917Z","caller":"traceutil/trace.go:171","msg":"trace[1674629496] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"147.28429ms","start":"2026-10-15T18:53:19.722623Z","end":"2026-10-15T18:53:19.869907Z","steps":["trace[1674629496] 'process raft request' (duration: 146.509582ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.870211Z","caller":"traceutil/trace.go:171","msg":"trace[945899588] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"147.468054ms","start":"2026-10-15T18:53:19.722699Z","end":"2026-10-15T18:53:19.870168Z","steps":["trace[945899588] 'process raft request' (duration: 146.471371ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.870308Z","caller":"traceutil/trace.go:171","msg":"trace[641710074] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"140.426993ms","start":"2026-10-15T18:53:19.729872Z","end":"2026-10-15T18:53:19.870299Z","steps":["trace[641710074] 'process raft request' (duration: 139.345088ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:19.870319Z","caller":"traceutil/trace.go:171","msg":"trace[457460475] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"131.826395ms","start":"2026-10-15T18:53:19.738482Z","end":"2026-10-15T18:53:19.870308Z","steps":["trace[457460475] 'process raft request' (duration: 131.695882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:53:26.335048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.683434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T18:53:26.335285Z","caller":"traceutil/trace.go:171","msg":"trace[2073715903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"258.015032ms","start":"2026-10-15T18:53:26.077245Z","end":"2026-10-15T18:53:26.33526Z","steps":["trace[2073715903] 'range keys from in-memory index tree' (duration: 257.530731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:53:26.335318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.618285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-10-15T18:53:26.335328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.148973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T18:53:26.335468Z","caller":"traceutil/trace.go:171","msg":"trace[1549960533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"251.81892ms","start":"2026-10-15T18:53:26.083616Z","end":"2026-10-15T18:53:26.335435Z","steps":["trace[1549960533] 'range keys from in-memory index tree' (duration: 251.334348ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:26.3355Z","caller":"traceutil/trace.go:171","msg":"trace[1183675417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"348.384808ms","start":"2026-10-15T18:53:25.98708Z","end":"2026-10-15T18:53:26.335464Z","steps":["trace[1183675417] 'range keys from in-memory index tree' (duration: 347.870436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:53:26.335579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:53:25.987063Z","time spent":"348.497491ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T18:53:26.452431Z","caller":"traceutil/trace.go:171","msg":"trace[855469208] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"115.282827ms","start":"2026-10-15T18:53:26.337112Z","end":"2026-10-15T18:53:26.452394Z","steps":["trace[855469208] 'read index received' (duration: 113.978247ms)","trace[855469208] 'applied index is now lower than readState.Index' (duration: 1.30304ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:53:26.452478Z","caller":"traceutil/trace.go:171","msg":"trace[1658011720] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"275.045971ms","start":"2026-10-15T18:53:26.177399Z","end":"2026-10-15T18:53:26.452445Z","steps":["trace[1658011720] 'process raft request' (duration: 274.088249ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:53:26.452649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.516842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T18:53:26.452693Z","caller":"traceutil/trace.go:171","msg":"trace[1604320198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"115.637085ms","start":"2026-10-15T18:53:26.337045Z","end":"2026-10-15T18:53:26.452682Z","steps":["trace[1604320198] 'agreement among raft nodes before linearized reading' (duration: 115.511022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:53:27.199906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.178005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T18:53:27.200166Z","caller":"traceutil/trace.go:171","msg":"trace[11732356] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"860.287583ms","start":"2026-10-15T18:53:26.339825Z","end":"2026-10-15T18:53:27.200112Z","steps":["trace[11732356] 'process raft request' (duration: 203.087075ms)","trace[11732356] 'compare' (duration: 656.035031ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:53:27.200368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:53:26.339796Z","time spent":"860.482588ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T18:53:27.200398Z","caller":"traceutil/trace.go:171","msg":"trace[264216236] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"860.092609ms","start":"2026-10-15T18:53:26.340288Z","end":"2026-10-15T18:53:27.200381Z","steps":["trace[264216236] 'process raft request' (duration: 859.738641ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:27.200451Z","caller":"traceutil/trace.go:171","msg":"trace[500564182] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"671.315621ms","start":"2026-10-15T18:53:26.529117Z","end":"2026-10-15T18:53:27.200432Z","steps":["trace[500564182] 'process raft request' (duration: 671.057575ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:53:27.200485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:53:26.340266Z","time spent":"860.173611ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T18:53:27.200539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:53:26.529078Z","time spent":"671.420284ms","remote":"127.0.0.1:52756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T18:53:27.200588Z","caller":"traceutil/trace.go:171","msg":"trace[1376267585] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:639; }","duration":"748.058697ms","start":"2026-10-15T18:53:26.452515Z","end":"2026-10-15T18:53:27.200574Z","steps":["trace[1376267585] 'read index received' (duration: 90.389767ms)","trace[1376267585] 'applied index is now lower than readState.Index' (duration: 657.66737ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:53:27.200887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.118633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T18:53:27.200891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.865908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T18:53:27.200952Z","caller":"traceutil/trace.go:171","msg":"trace[432063042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"123.219316ms","start":"2026-10-15T18:53:27.077714Z","end":"2026-10-15T18:53:27.200933Z","steps":["trace[432063042] 'agreement among raft nodes before linearized reading' (duration: 123.128324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:53:27.200893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.574646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-24x6g\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-10-15T18:53:27.200978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.030416ms","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-15T18:53:27.200995Z","caller":"traceutil/trace.go:171","msg":"trace[963145128] range","detail":"{range_begin:/registry/pods/kube-system/cilium-24x6g; range_end:; response_count:1; response_revision:614; }","duration":"702.726449ms","start":"2026-10-15T18:53:26.498259Z","end":"2026-10-15T18:53:27.200985Z","steps":["trace[963145128] 'agreement among raft nodes before linearized reading' (duration: 702.464873ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:53:27.201012Z","caller":"traceutil/trace.go:171","msg":"trace[633228908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"424.104127ms","start":"2026-10-15T18:53:26.776894Z","end":"2026-10-15T18:53:27.200998Z","steps":["trace[633228908] 'agreement among raft nodes before linearized reading' (duration: 424.026826ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:53:27.201033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:53:26.498236Z","time spent":"702.785541ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-24x6g\" "} {"level":"warn","ts":"2026-10-15T18:53:27.201041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:53:26.776868Z","time spent":"424.165238ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T18:53:27.200956Z","caller":"traceutil/trace.go:171","msg":"trace[1355915964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"791.988091ms","start":"2026-10-15T18:53:26.408954Z","end":"2026-10-15T18:53:27.200942Z","steps":["trace[1355915964] 'agreement among raft nodes before linearized reading' (duration: 791.719245ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:53:27.20117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:53:26.408931Z","time spent":"792.218697ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T18:54:13.436158Z","caller":"traceutil/trace.go:171","msg":"trace[172490444] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"116.397481ms","start":"2026-10-15T18:54:13.319733Z","end":"2026-10-15T18:54:13.43613Z","steps":["trace[172490444] 'process raft request' (duration: 116.216347ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:54:35.124323Z","caller":"traceutil/trace.go:171","msg":"trace[1802985779] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"149.923481ms","start":"2026-10-15T18:54:34.97436Z","end":"2026-10-15T18:54:35.124283Z","steps":["trace[1802985779] 'process raft request' (duration: 149.670025ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:54:53.379922Z","caller":"traceutil/trace.go:171","msg":"trace[938436922] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"165.302127ms","start":"2026-10-15T18:54:53.214587Z","end":"2026-10-15T18:54:53.379889Z","steps":["trace[938436922] 'process raft request' (duration: 165.148234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:01.421553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.405112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-87f4ed59-3772-438f-b1e0-8eeda1eaa3dc\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-15T18:55:01.421654Z","caller":"traceutil/trace.go:171","msg":"trace[1620352239] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-87f4ed59-3772-438f-b1e0-8eeda1eaa3dc; range_end:; response_count:1; response_revision:1857; }","duration":"372.509524ms","start":"2026-10-15T18:55:01.049119Z","end":"2026-10-15T18:55:01.421629Z","steps":["trace[1620352239] 'range keys from in-memory index tree' (duration: 372.188667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:01.421706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:55:01.049056Z","time spent":"372.635876ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-87f4ed59-3772-438f-b1e0-8eeda1eaa3dc\" "} {"level":"warn","ts":"2026-10-15T18:55:01.421948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.648549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T18:55:01.422005Z","caller":"traceutil/trace.go:171","msg":"trace[204127512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"343.740241ms","start":"2026-10-15T18:55:01.078249Z","end":"2026-10-15T18:55:01.42199Z","steps":["trace[204127512] 'range keys from in-memory index tree' (duration: 343.551947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:01.422016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.49675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-87f4ed59-3772-438f-b1e0-8eeda1eaa3dc\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-10-15T18:55:01.422033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:55:01.07823Z","time spent":"343.796342ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T18:55:01.422062Z","caller":"traceutil/trace.go:171","msg":"trace[1154811784] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-87f4ed59-3772-438f-b1e0-8eeda1eaa3dc; range_end:; response_count:1; response_revision:1857; }","duration":"200.580392ms","start":"2026-10-15T18:55:01.221468Z","end":"2026-10-15T18:55:01.422048Z","steps":["trace[1154811784] 'range keys from in-memory index tree' (duration: 200.367098ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:55:01.718855Z","caller":"traceutil/trace.go:171","msg":"trace[1971165294] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1934; }","duration":"295.319339ms","start":"2026-10-15T18:55:01.42351Z","end":"2026-10-15T18:55:01.718829Z","steps":["trace[1971165294] 'read index received' (duration: 295.243798ms)","trace[1971165294] 'applied index is now lower than readState.Index' (duration: 74.051µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:55:01.718914Z","caller":"traceutil/trace.go:171","msg":"trace[666414061] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"304.367902ms","start":"2026-10-15T18:55:01.414515Z","end":"2026-10-15T18:55:01.718883Z","steps":["trace[666414061] 'process raft request' (duration: 304.102496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:01.719034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.493693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T18:55:01.71925Z","caller":"traceutil/trace.go:171","msg":"trace[357630760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1858; }","duration":"295.756059ms","start":"2026-10-15T18:55:01.423475Z","end":"2026-10-15T18:55:01.719231Z","steps":["trace[357630760] 'agreement among raft nodes before linearized reading' (duration: 295.455303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:01.719273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:55:01.414485Z","time spent":"304.633638ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T18:55:02.007353Z","caller":"traceutil/trace.go:171","msg":"trace[427627217] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"574.304604ms","start":"2026-10-15T18:55:01.433016Z","end":"2026-10-15T18:55:02.00732Z","steps":["trace[427627217] 'process raft request' (duration: 574.093039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:02.007551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.215772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-10-15T18:55:02.007559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:55:01.432984Z","time spent":"574.464546ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T18:55:02.0076Z","caller":"traceutil/trace.go:171","msg":"trace[1468908258] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1859; }","duration":"370.337946ms","start":"2026-10-15T18:55:01.63725Z","end":"2026-10-15T18:55:02.007588Z","steps":["trace[1468908258] 'agreement among raft nodes before linearized reading' (duration: 370.152901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:02.007636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:55:01.637219Z","time spent":"370.409037ms","remote":"127.0.0.1:43736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-15T18:55:02.007346Z","caller":"traceutil/trace.go:171","msg":"trace[1536687513] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"288.367234ms","start":"2026-10-15T18:55:01.718923Z","end":"2026-10-15T18:55:02.00729Z","steps":["trace[1536687513] 'read index received' (duration: 288.317683ms)","trace[1536687513] 'applied index is now lower than readState.Index' (duration: 47.921µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:55:02.008224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.836129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T18:55:02.008256Z","caller":"traceutil/trace.go:171","msg":"trace[1217702162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1859; }","duration":"243.90507ms","start":"2026-10-15T18:55:01.764341Z","end":"2026-10-15T18:55:02.008246Z","steps":["trace[1217702162] 'agreement among raft nodes before linearized reading' (duration: 243.761987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:02.008439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.790046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T18:55:02.008494Z","caller":"traceutil/trace.go:171","msg":"trace[1801204664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1859; }","duration":"153.874559ms","start":"2026-10-15T18:55:01.854604Z","end":"2026-10-15T18:55:02.008479Z","steps":["trace[1801204664] 'agreement among raft nodes before linearized reading' (duration: 153.690314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:02.008467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.45894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T18:55:02.00855Z","caller":"traceutil/trace.go:171","msg":"trace[799185564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1859; }","duration":"136.611263ms","start":"2026-10-15T18:55:01.87192Z","end":"2026-10-15T18:55:02.008531Z","steps":["trace[799185564] 'agreement among raft nodes before linearized reading' (duration: 136.357947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:02.00846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.163169ms","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-15T18:55:02.008608Z","caller":"traceutil/trace.go:171","msg":"trace[2057883740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1859; }","duration":"160.351893ms","start":"2026-10-15T18:55:01.848245Z","end":"2026-10-15T18:55:02.008597Z","steps":["trace[2057883740] 'agreement among raft nodes before linearized reading' (duration: 160.152359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:02.008695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.670122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-15T18:55:02.009442Z","caller":"traceutil/trace.go:171","msg":"trace[1593338484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1859; }","duration":"136.531601ms","start":"2026-10-15T18:55:01.872882Z","end":"2026-10-15T18:55:02.009414Z","steps":["trace[1593338484] 'agreement among raft nodes before linearized reading' (duration: 135.548819ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:02.527658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.521242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-10-15T18:55:02.527948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.139987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-87f4ed59-3772-438f-b1e0-8eeda1eaa3dc\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-10-15T18:55:02.527988Z","caller":"traceutil/trace.go:171","msg":"trace[311659371] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-87f4ed59-3772-438f-b1e0-8eeda1eaa3dc; range_end:; response_count:1; response_revision:1865; }","duration":"100.2417ms","start":"2026-10-15T18:55:02.42773Z","end":"2026-10-15T18:55:02.527972Z","steps":["trace[311659371] 'agreement among raft nodes before linearized reading' (duration: 100.004115ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:55:02.528049Z","caller":"traceutil/trace.go:171","msg":"trace[45735465] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"286.220265ms","start":"2026-10-15T18:55:02.241798Z","end":"2026-10-15T18:55:02.528018Z","steps":["trace[45735465] 'process raft request' (duration: 174.253173ms)","trace[45735465] 'compare' (duration: 111.234986ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:55:36.025836Z","caller":"traceutil/trace.go:171","msg":"trace[1467256239] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"148.621495ms","start":"2026-10-15T18:55:35.877185Z","end":"2026-10-15T18:55:36.025807Z","steps":["trace[1467256239] 'process raft request' (duration: 148.46897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:55:36.796589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.761531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T18:55:36.797086Z","caller":"traceutil/trace.go:171","msg":"trace[1562667253] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"254.798561ms","start":"2026-10-15T18:55:36.542258Z","end":"2026-10-15T18:55:36.797057Z","steps":["trace[1562667253] 'process raft request' (duration: 31.501965ms)","trace[1562667253] 'compare' (duration: 222.616487ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:55:37.043591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.529271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T18:55:37.043849Z","caller":"traceutil/trace.go:171","msg":"trace[1577337967] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"237.979438ms","start":"2026-10-15T18:55:36.80585Z","end":"2026-10-15T18:55:37.04383Z","steps":["trace[1577337967] 'process raft request' (duration: 237.920966ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:55:37.043865Z","caller":"traceutil/trace.go:171","msg":"trace[1227312659] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"242.424152ms","start":"2026-10-15T18:55:36.801388Z","end":"2026-10-15T18:55:37.043812Z","steps":["trace[1227312659] 'process raft request' (duration: 116.588192ms)","trace[1227312659] 'compare' (duration: 125.373017ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:55:37.043887Z","caller":"traceutil/trace.go:171","msg":"trace[151143470] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2192; }","duration":"239.824298ms","start":"2026-10-15T18:55:36.80404Z","end":"2026-10-15T18:55:37.043865Z","steps":["trace[151143470] 'read index received' (duration: 113.921087ms)","trace[151143470] 'applied index is now lower than readState.Index' (duration: 125.901971ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:55:37.044047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.000634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T18:55:37.04409Z","caller":"traceutil/trace.go:171","msg":"trace[332971309] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2110; }","duration":"240.089846ms","start":"2026-10-15T18:55:36.803988Z","end":"2026-10-15T18:55:37.044078Z","steps":["trace[332971309] 'agreement among raft nodes before linearized reading' (duration: 239.945002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:57:13.037331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.970541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T18:57:13.037549Z","caller":"traceutil/trace.go:171","msg":"trace[885366052] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2731; }","duration":"149.239058ms","start":"2026-10-15T18:57:12.888289Z","end":"2026-10-15T18:57:13.037528Z","steps":["trace[885366052] 'range keys from in-memory index tree' (duration: 148.764095ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:57:13.292581Z","caller":"traceutil/trace.go:171","msg":"trace[960047929] linearizableReadLoop","detail":"{readStateIndex:2843; appliedIndex:2842; }","duration":"149.207325ms","start":"2026-10-15T18:57:13.143342Z","end":"2026-10-15T18:57:13.29255Z","steps":["trace[960047929] 'read index received' (duration: 148.996449ms)","trace[960047929] 'applied index is now lower than readState.Index' (duration: 206.326µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:57:13.29263Z","caller":"traceutil/trace.go:171","msg":"trace[703177637] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"152.799196ms","start":"2026-10-15T18:57:13.139797Z","end":"2026-10-15T18:57:13.292596Z","steps":["trace[703177637] 'process raft request' (duration: 152.54676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:57:13.292856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.488552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-10-15T18:57:13.29292Z","caller":"traceutil/trace.go:171","msg":"trace[158908543] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2736; }","duration":"149.589965ms","start":"2026-10-15T18:57:13.143314Z","end":"2026-10-15T18:57:13.292904Z","steps":["trace[158908543] 'agreement among raft nodes before linearized reading' (duration: 149.367799ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:57:18.547643Z","caller":"traceutil/trace.go:171","msg":"trace[1906669220] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"128.733454ms","start":"2026-10-15T18:57:18.418873Z","end":"2026-10-15T18:57:18.547607Z","steps":["trace[1906669220] 'process raft request' (duration: 128.446207ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:00:52.817271Z","caller":"traceutil/trace.go:171","msg":"trace[1966871664] transaction","detail":"{read_only:false; response_revision:4038; number_of_response:1; }","duration":"155.157886ms","start":"2026-10-15T19:00:52.662091Z","end":"2026-10-15T19:00:52.817249Z","steps":["trace[1966871664] 'process raft request' (duration: 113.604608ms)","trace[1966871664] 'compare' (duration: 41.163019ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:00:52.81734Z","caller":"traceutil/trace.go:171","msg":"trace[421563510] transaction","detail":"{read_only:false; response_revision:4039; number_of_response:1; }","duration":"126.406526ms","start":"2026-10-15T19:00:52.690908Z","end":"2026-10-15T19:00:52.817314Z","steps":["trace[421563510] 'process raft request' (duration: 126.197751ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:37.664526Z","caller":"traceutil/trace.go:171","msg":"trace[1102320751] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"118.542848ms","start":"2026-10-15T19:01:37.545944Z","end":"2026-10-15T19:01:37.664487Z","steps":["trace[1102320751] 'process raft request' (duration: 118.342973ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:37.664838Z","caller":"traceutil/trace.go:171","msg":"trace[1728354880] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"112.249102ms","start":"2026-10-15T19:01:37.552555Z","end":"2026-10-15T19:01:37.664804Z","steps":["trace[1728354880] 'process raft request' (duration: 111.831402ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:37.738874Z","caller":"traceutil/trace.go:171","msg":"trace[973985413] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"124.960686ms","start":"2026-10-15T19:01:37.613891Z","end":"2026-10-15T19:01:37.738851Z","steps":["trace[973985413] 'process raft request' (duration: 93.774673ms)","trace[973985413] 'compare' (duration: 31.0338ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:01:37.738928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.501569ms","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-10-15T19:01:37.738997Z","caller":"traceutil/trace.go:171","msg":"trace[1138383150] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4691; }","duration":"125.620901ms","start":"2026-10-15T19:01:37.613359Z","end":"2026-10-15T19:01:37.73898Z","steps":["trace[1138383150] 'agreement among raft nodes before linearized reading' (duration: 125.487528ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:37.877117Z","caller":"traceutil/trace.go:171","msg":"trace[1761182370] transaction","detail":"{read_only:false; response_revision:4697; number_of_response:1; }","duration":"110.996373ms","start":"2026-10-15T19:01:37.766102Z","end":"2026-10-15T19:01:37.877098Z","steps":["trace[1761182370] 'process raft request' (duration: 102.563257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:37.877293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.052504ms","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-10-15T19:01:37.877375Z","caller":"traceutil/trace.go:171","msg":"trace[105939389] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4697; }","duration":"114.147306ms","start":"2026-10-15T19:01:37.763184Z","end":"2026-10-15T19:01:37.877332Z","steps":["trace[105939389] 'agreement among raft nodes before linearized reading' (duration: 113.930001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:37.877432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.318829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-10-15T19:01:37.877528Z","caller":"traceutil/trace.go:171","msg":"trace[348503909] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4697; }","duration":"104.453691ms","start":"2026-10-15T19:01:37.773052Z","end":"2026-10-15T19:01:37.877505Z","steps":["trace[348503909] 'agreement among raft nodes before linearized reading' (duration: 104.173615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:37.877522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.080398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0.18dec92d3502e293\" ","response":"range_response_count:1 size:1203"} {"level":"info","ts":"2026-10-15T19:01:37.877603Z","caller":"traceutil/trace.go:171","msg":"trace[1501673837] range","detail":"{range_begin:/registry/events/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0.18dec92d3502e293; range_end:; response_count:1; response_revision:4697; }","duration":"115.19463ms","start":"2026-10-15T19:01:37.762388Z","end":"2026-10-15T19:01:37.877582Z","steps":["trace[1501673837] 'agreement among raft nodes before linearized reading' (duration: 114.952794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:38.256575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.795731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:01:38.256767Z","caller":"traceutil/trace.go:171","msg":"trace[1558203828] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"322.696409ms","start":"2026-10-15T19:01:37.934052Z","end":"2026-10-15T19:01:38.256748Z","steps":["trace[1558203828] 'process raft request' (duration: 84.659572ms)","trace[1558203828] 'compare' (duration: 237.598387ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:01:38.256834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:37.934032Z","time spent":"322.772201ms","remote":"127.0.0.1:52722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:01:38.256928Z","caller":"traceutil/trace.go:171","msg":"trace[1527461263] linearizableReadLoop","detail":"{readStateIndex:4887; appliedIndex:4885; }","duration":"304.937168ms","start":"2026-10-15T19:01:37.951966Z","end":"2026-10-15T19:01:38.256903Z","steps":["trace[1527461263] 'read index received' (duration: 66.636235ms)","trace[1527461263] 'applied index is now lower than readState.Index' (duration: 238.298953ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:38.256968Z","caller":"traceutil/trace.go:171","msg":"trace[60720723] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"318.732837ms","start":"2026-10-15T19:01:37.938198Z","end":"2026-10-15T19:01:38.256931Z","steps":["trace[60720723] 'process raft request' (duration: 318.487422ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:38.257101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.03041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:01:38.257142Z","caller":"traceutil/trace.go:171","msg":"trace[205908485] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:0; response_revision:4703; }","duration":"302.107952ms","start":"2026-10-15T19:01:37.955023Z","end":"2026-10-15T19:01:38.257131Z","steps":["trace[205908485] 'agreement among raft nodes before linearized reading' (duration: 302.02637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:38.257147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:37.938186Z","time spent":"318.85893ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:01:38.257172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:37.955014Z","time spent":"302.149843ms","remote":"127.0.0.1:52342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-10-15T19:01:38.25717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.189583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-zd7c9\" ","response":"range_response_count:1 size:5042"} {"level":"warn","ts":"2026-10-15T19:01:38.257248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.495431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-10-15T19:01:38.257251Z","caller":"traceutil/trace.go:171","msg":"trace[1249866589] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-zd7c9; range_end:; response_count:1; response_revision:4703; }","duration":"305.297176ms","start":"2026-10-15T19:01:37.951929Z","end":"2026-10-15T19:01:38.257226Z","steps":["trace[1249866589] 'agreement among raft nodes before linearized reading' (duration: 305.078531ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:38.257293Z","caller":"traceutil/trace.go:171","msg":"trace[495585092] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4703; }","duration":"113.578973ms","start":"2026-10-15T19:01:38.143701Z","end":"2026-10-15T19:01:38.25728Z","steps":["trace[495585092] 'agreement among raft nodes before linearized reading' (duration: 113.432519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:38.257307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:37.951915Z","time spent":"305.379078ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5065,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-zd7c9\" "} {"level":"warn","ts":"2026-10-15T19:01:38.257407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.687232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-10-15T19:01:38.257488Z","caller":"traceutil/trace.go:171","msg":"trace[1311966462] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4703; }","duration":"285.806734ms","start":"2026-10-15T19:01:37.971661Z","end":"2026-10-15T19:01:38.257468Z","steps":["trace[1311966462] 'agreement among raft nodes before linearized reading' (duration: 285.662491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:45.339617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.640317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:01:45.339788Z","caller":"traceutil/trace.go:171","msg":"trace[1122057094] linearizableReadLoop","detail":"{readStateIndex:5065; appliedIndex:5063; }","duration":"171.654945ms","start":"2026-10-15T19:01:45.168118Z","end":"2026-10-15T19:01:45.339773Z","steps":["trace[1122057094] 'read index received' (duration: 43.749913ms)","trace[1122057094] 'applied index is now lower than readState.Index' (duration: 127.904052ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:45.339881Z","caller":"traceutil/trace.go:171","msg":"trace[1399451356] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4878; }","duration":"172.502275ms","start":"2026-10-15T19:01:45.167356Z","end":"2026-10-15T19:01:45.339859Z","steps":["trace[1399451356] 'process raft request' (duration: 172.373923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:45.339959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.8415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-15T19:01:45.340202Z","caller":"traceutil/trace.go:171","msg":"trace[949995232] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"175.31448ms","start":"2026-10-15T19:01:45.164877Z","end":"2026-10-15T19:01:45.340191Z","steps":["trace[949995232] 'process raft request' (duration: 47.013549ms)","trace[949995232] 'compare' (duration: 127.353359ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:45.346589Z","caller":"traceutil/trace.go:171","msg":"trace[549980143] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4878; }","duration":"178.469774ms","start":"2026-10-15T19:01:45.168084Z","end":"2026-10-15T19:01:45.346554Z","steps":["trace[549980143] 'agreement among raft nodes before linearized reading' (duration: 171.769548ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:50.091344Z","caller":"traceutil/trace.go:171","msg":"trace[1149645407] linearizableReadLoop","detail":"{readStateIndex:5158; appliedIndex:5157; }","duration":"344.794612ms","start":"2026-10-15T19:01:49.74652Z","end":"2026-10-15T19:01:50.091315Z","steps":["trace[1149645407] 'read index received' (duration: 323.54374ms)","trace[1149645407] 'applied index is now lower than readState.Index' (duration: 21.249382ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:50.091502Z","caller":"traceutil/trace.go:171","msg":"trace[1117567416] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"348.419766ms","start":"2026-10-15T19:01:49.743064Z","end":"2026-10-15T19:01:50.091484Z","steps":["trace[1117567416] 'process raft request' (duration: 326.946629ms)","trace[1117567416] 'compare' (duration: 20.72106ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:01:50.091667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:49.743037Z","time spent":"348.501439ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:01:50.091954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.432878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-65261d1c-fc6d-4e0a-9a62-b7008f172e16\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-15T19:01:50.092003Z","caller":"traceutil/trace.go:171","msg":"trace[1241064312] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-65261d1c-fc6d-4e0a-9a62-b7008f172e16; range_end:; response_count:1; response_revision:4969; }","duration":"345.51989ms","start":"2026-10-15T19:01:49.746468Z","end":"2026-10-15T19:01:50.091988Z","steps":["trace[1241064312] 'agreement among raft nodes before linearized reading' (duration: 345.338156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:50.092052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:49.746406Z","time spent":"345.634962ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-65261d1c-fc6d-4e0a-9a62-b7008f172e16\" "} {"level":"warn","ts":"2026-10-15T19:01:50.092149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.187552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-dvnlw-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-10-15T19:01:50.092228Z","caller":"traceutil/trace.go:171","msg":"trace[941809975] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-dvnlw-tls; range_end:; response_count:1; response_revision:4969; }","duration":"310.317055ms","start":"2026-10-15T19:01:49.78189Z","end":"2026-10-15T19:01:50.092207Z","steps":["trace[941809975] 'agreement among raft nodes before linearized reading' (duration: 310.09612ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:50.092266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:49.781872Z","time spent":"310.386026ms","remote":"127.0.0.1:51914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":1990,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-dvnlw-tls\" "} {"level":"warn","ts":"2026-10-15T19:01:50.869763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.287297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T19:01:50.869854Z","caller":"traceutil/trace.go:171","msg":"trace[588165087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4975; }","duration":"105.41739ms","start":"2026-10-15T19:01:50.764412Z","end":"2026-10-15T19:01:50.86983Z","steps":["trace[588165087] 'range keys from in-memory index tree' (duration: 105.099683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:50.870046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.580667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T19:01:50.870077Z","caller":"traceutil/trace.go:171","msg":"trace[205938567] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4975; }","duration":"160.639379ms","start":"2026-10-15T19:01:50.70943Z","end":"2026-10-15T19:01:50.870069Z","steps":["trace[205938567] 'range keys from in-memory index tree' (duration: 160.452914ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:50.87042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.844721ms","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-10-15T19:01:50.870545Z","caller":"traceutil/trace.go:171","msg":"trace[1295339497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4975; }","duration":"170.019155ms","start":"2026-10-15T19:01:50.700501Z","end":"2026-10-15T19:01:50.870521Z","steps":["trace[1295339497] 'range keys from in-memory index tree' (duration: 169.180587ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:50.984469Z","caller":"traceutil/trace.go:171","msg":"trace[889099092] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"110.745724ms","start":"2026-10-15T19:01:50.873693Z","end":"2026-10-15T19:01:50.984439Z","steps":["trace[889099092] 'process raft request' (duration: 110.613051ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:51.044176Z","caller":"traceutil/trace.go:171","msg":"trace[1936665657] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"169.427542ms","start":"2026-10-15T19:01:50.874725Z","end":"2026-10-15T19:01:51.044152Z","steps":["trace[1936665657] 'process raft request' (duration: 169.244758ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:51.044479Z","caller":"traceutil/trace.go:171","msg":"trace[596574053] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"169.577595ms","start":"2026-10-15T19:01:50.874887Z","end":"2026-10-15T19:01:51.044464Z","steps":["trace[596574053] 'process raft request' (duration: 169.451222ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:51.044496Z","caller":"traceutil/trace.go:171","msg":"trace[1023334772] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"169.661377ms","start":"2026-10-15T19:01:50.874799Z","end":"2026-10-15T19:01:51.044461Z","steps":["trace[1023334772] 'process raft request' (duration: 169.304219ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:06.577421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5252218890597763258,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-15T19:02:06.687518Z","caller":"traceutil/trace.go:171","msg":"trace[1266545944] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"632.703785ms","start":"2026-10-15T19:02:06.054792Z","end":"2026-10-15T19:02:06.687496Z","steps":["trace[1266545944] 'process raft request' (duration: 632.567892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:06.687711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:06.054766Z","time spent":"632.823098ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:02:06.710963Z","caller":"traceutil/trace.go:171","msg":"trace[1061075875] transaction","detail":"{read_only:false; response_revision:5172; number_of_response:1; }","duration":"564.227323ms","start":"2026-10-15T19:02:06.146712Z","end":"2026-10-15T19:02:06.710939Z","steps":["trace[1061075875] 'process raft request' (duration: 563.910356ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:02:06.710975Z","caller":"traceutil/trace.go:171","msg":"trace[1294202409] linearizableReadLoop","detail":"{readStateIndex:5368; appliedIndex:5366; }","duration":"633.601566ms","start":"2026-10-15T19:02:06.077338Z","end":"2026-10-15T19:02:06.710939Z","steps":["trace[1294202409] 'read index received' (duration: 610.117243ms)","trace[1294202409] 'applied index is now lower than readState.Index' (duration: 23.482703ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:02:06.711061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:06.146684Z","time spent":"564.328256ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:02:06.711188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.840842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:02:06.711246Z","caller":"traceutil/trace.go:171","msg":"trace[881015273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5174; }","duration":"633.957024ms","start":"2026-10-15T19:02:06.077272Z","end":"2026-10-15T19:02:06.71123Z","steps":["trace[881015273] 'agreement among raft nodes before linearized reading' (duration: 633.853732ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:06.711291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.959422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10395"} {"level":"warn","ts":"2026-10-15T19:02:06.711282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:06.077255Z","time spent":"634.017736ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T19:02:06.71132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.462849ms","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-10-15T19:02:06.71137Z","caller":"traceutil/trace.go:171","msg":"trace[1683714516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5174; }","duration":"269.5117ms","start":"2026-10-15T19:02:06.441844Z","end":"2026-10-15T19:02:06.711356Z","steps":["trace[1683714516] 'agreement among raft nodes before linearized reading' (duration: 269.363187ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:06.711411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.159182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-15T19:02:06.711333Z","caller":"traceutil/trace.go:171","msg":"trace[309771862] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5174; }","duration":"431.036534ms","start":"2026-10-15T19:02:06.280286Z","end":"2026-10-15T19:02:06.711322Z","steps":["trace[309771862] 'agreement among raft nodes before linearized reading' (duration: 430.881931ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:02:06.711445Z","caller":"traceutil/trace.go:171","msg":"trace[1434934580] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5174; }","duration":"365.189673ms","start":"2026-10-15T19:02:06.346245Z","end":"2026-10-15T19:02:06.711434Z","steps":["trace[1434934580] 'agreement among raft nodes before linearized reading' (duration: 365.105511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:06.711462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:06.280266Z","time spent":"431.187137ms","remote":"127.0.0.1:52440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10418,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-10-15T19:02:06.711472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:06.346196Z","time spent":"365.269505ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-15T19:02:33.916398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2845} {"level":"info","ts":"2026-10-15T19:02:33.986124Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2845,"took":"68.594672ms","hash":295194046,"current-db-size-bytes":51716096,"current-db-size":"52 MB","current-db-size-in-use-bytes":27750400,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T19:02:33.986198Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":295194046,"revision":2845,"compact-revision":-1} {"level":"info","ts":"2026-10-15T19:07:33.925204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5352} {"level":"info","ts":"2026-10-15T19:07:34.004813Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5352,"took":"77.883422ms","hash":4187886159,"current-db-size-bytes":51716096,"current-db-size":"52 MB","current-db-size-in-use-bytes":18632704,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-15T19:07:34.004901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4187886159,"revision":5352,"compact-revision":2845}