{"level":"warn","ts":"2026-10-09T05:09:38.108811Z","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-09T05:09:38.11036Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.167:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.167:2380","--initial-cluster=instance=https://199.204.45.167:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.167:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.167: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-09T05:09:38.110425Z","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-09T05:09:38.110436Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.167:2380"]} {"level":"info","ts":"2026-10-09T05:09:38.110452Z","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-09T05:09:38.111121Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.167:2379"]} {"level":"info","ts":"2026-10-09T05:09:38.111252Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.167:2380"],"listen-peer-urls":["https://199.204.45.167:2380"],"advertise-client-urls":["https://199.204.45.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.167: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-09T05:09:38.115553Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.099437ms"} {"level":"info","ts":"2026-10-09T05:09:38.127417Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d23d007b0b9ea59a","cluster-id":"9c2438a46de60de2"} {"level":"info","ts":"2026-10-09T05:09:38.127498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d23d007b0b9ea59a switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T05:09:38.127529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d23d007b0b9ea59a became follower at term 0"} {"level":"info","ts":"2026-10-09T05:09:38.127548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d23d007b0b9ea59a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T05:09:38.127575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d23d007b0b9ea59a became follower at term 1"} {"level":"info","ts":"2026-10-09T05:09:38.127639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d23d007b0b9ea59a switched to configuration voters=(15149265250020140442)"} {"level":"warn","ts":"2026-10-09T05:09:38.138595Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T05:09:38.141302Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T05:09:38.143421Z","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-09T05:09:38.147945Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d23d007b0b9ea59a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T05:09:38.148309Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d23d007b0b9ea59a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T05:09:38.148452Z","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-09T05:09:38.14848Z","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-09T05:09:38.148487Z","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-09T05:09:38.149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d23d007b0b9ea59a switched to configuration voters=(15149265250020140442)"} {"level":"info","ts":"2026-10-09T05:09:38.14963Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9c2438a46de60de2","local-member-id":"d23d007b0b9ea59a","added-peer-id":"d23d007b0b9ea59a","added-peer-peer-urls":["https://199.204.45.167:2380"]} {"level":"info","ts":"2026-10-09T05:09:38.153701Z","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-09T05:09:38.153777Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.167:2380"} {"level":"info","ts":"2026-10-09T05:09:38.153807Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.167:2380"} {"level":"info","ts":"2026-10-09T05:09:38.154154Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d23d007b0b9ea59a","initial-advertise-peer-urls":["https://199.204.45.167:2380"],"listen-peer-urls":["https://199.204.45.167:2380"],"advertise-client-urls":["https://199.204.45.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T05:09:38.154201Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T05:09:38.729052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d23d007b0b9ea59a is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T05:09:38.729116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d23d007b0b9ea59a became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T05:09:38.729173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d23d007b0b9ea59a received MsgPreVoteResp from d23d007b0b9ea59a at term 1"} {"level":"info","ts":"2026-10-09T05:09:38.729196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d23d007b0b9ea59a became candidate at term 2"} {"level":"info","ts":"2026-10-09T05:09:38.729212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d23d007b0b9ea59a received MsgVoteResp from d23d007b0b9ea59a at term 2"} {"level":"info","ts":"2026-10-09T05:09:38.729226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d23d007b0b9ea59a became leader at term 2"} {"level":"info","ts":"2026-10-09T05:09:38.729243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d23d007b0b9ea59a elected leader d23d007b0b9ea59a at term 2"} {"level":"info","ts":"2026-10-09T05:09:38.730485Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d23d007b0b9ea59a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.167:2379]}","request-path":"/0/members/d23d007b0b9ea59a/attributes","cluster-id":"9c2438a46de60de2","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T05:09:38.73051Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T05:09:38.730573Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:09:38.730739Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T05:09:38.730936Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T05:09:38.73097Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T05:09:38.732228Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9c2438a46de60de2","local-member-id":"d23d007b0b9ea59a","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:09:38.732473Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:09:38.732622Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:09:38.733761Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T05:09:38.734139Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.167:2379"} {"level":"info","ts":"2026-10-09T05:10:05.175798Z","caller":"traceutil/trace.go:171","msg":"trace[2082489302] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:454; }","duration":"207.793573ms","start":"2026-10-09T05:10:04.967981Z","end":"2026-10-09T05:10:05.175774Z","steps":["trace[2082489302] 'read index received' (duration: 207.573654ms)","trace[2082489302] 'applied index is now lower than readState.Index' (duration: 219.039µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:10:05.176103Z","caller":"traceutil/trace.go:171","msg":"trace[1240127082] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"359.472003ms","start":"2026-10-09T05:10:04.816621Z","end":"2026-10-09T05:10:05.176093Z","steps":["trace[1240127082] 'process raft request' (duration: 359.013064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:10:05.176577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:10:04.8166Z","time spent":"359.528835ms","remote":"127.0.0.1:44980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:10:05.176803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.819404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:10:05.176844Z","caller":"traceutil/trace.go:171","msg":"trace[846893926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:439; }","duration":"208.904508ms","start":"2026-10-09T05:10:04.967928Z","end":"2026-10-09T05:10:05.176833Z","steps":["trace[846893926] 'agreement among raft nodes before linearized reading' (duration: 208.846006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:10:05.176991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.361438ms","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-09T05:10:05.177015Z","caller":"traceutil/trace.go:171","msg":"trace[15546777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:439; }","duration":"163.431051ms","start":"2026-10-09T05:10:05.013576Z","end":"2026-10-09T05:10:05.177007Z","steps":["trace[15546777] 'agreement among raft nodes before linearized reading' (duration: 163.387089ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:08.803901Z","caller":"traceutil/trace.go:171","msg":"trace[599192204] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"113.500584ms","start":"2026-10-09T05:10:08.690372Z","end":"2026-10-09T05:10:08.803872Z","steps":["trace[599192204] 'process raft request' (duration: 63.002194ms)","trace[599192204] 'compare' (duration: 50.304112ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:10:08.828573Z","caller":"traceutil/trace.go:171","msg":"trace[1788515512] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"115.96352ms","start":"2026-10-09T05:10:08.712582Z","end":"2026-10-09T05:10:08.828545Z","steps":["trace[1788515512] 'process raft request' (duration: 115.72397ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:08.828667Z","caller":"traceutil/trace.go:171","msg":"trace[911833651] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"112.91445ms","start":"2026-10-09T05:10:08.715729Z","end":"2026-10-09T05:10:08.828643Z","steps":["trace[911833651] 'process raft request' (duration: 112.828996ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:08.828612Z","caller":"traceutil/trace.go:171","msg":"trace[285190908] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"115.98686ms","start":"2026-10-09T05:10:08.712588Z","end":"2026-10-09T05:10:08.828575Z","steps":["trace[285190908] 'process raft request' (duration: 115.835154ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:08.829454Z","caller":"traceutil/trace.go:171","msg":"trace[593424666] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"114.694696ms","start":"2026-10-09T05:10:08.713946Z","end":"2026-10-09T05:10:08.828641Z","steps":["trace[593424666] 'process raft request' (duration: 114.543009ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:27.437408Z","caller":"traceutil/trace.go:171","msg":"trace[534705187] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"144.903889ms","start":"2026-10-09T05:10:27.292474Z","end":"2026-10-09T05:10:27.437378Z","steps":["trace[534705187] 'process raft request' (duration: 144.647844ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:28.707676Z","caller":"traceutil/trace.go:171","msg":"trace[925614334] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"125.375268ms","start":"2026-10-09T05:10:28.582272Z","end":"2026-10-09T05:10:28.707647Z","steps":["trace[925614334] 'read index received' (duration: 124.567686ms)","trace[925614334] 'applied index is now lower than readState.Index' (duration: 806.822µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:10:28.707745Z","caller":"traceutil/trace.go:171","msg":"trace[1785972496] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"204.178845ms","start":"2026-10-09T05:10:28.503541Z","end":"2026-10-09T05:10:28.707719Z","steps":["trace[1785972496] 'process raft request' (duration: 203.571503ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:10:28.7079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.610851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T05:10:28.707956Z","caller":"traceutil/trace.go:171","msg":"trace[1425516527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"125.719376ms","start":"2026-10-09T05:10:28.582221Z","end":"2026-10-09T05:10:28.707941Z","steps":["trace[1425516527] 'agreement among raft nodes before linearized reading' (duration: 125.538017ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:28.85085Z","caller":"traceutil/trace.go:171","msg":"trace[1354227706] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"132.977656ms","start":"2026-10-09T05:10:28.717849Z","end":"2026-10-09T05:10:28.850826Z","steps":["trace[1354227706] 'process raft request' (duration: 132.845219ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:29.477844Z","caller":"traceutil/trace.go:171","msg":"trace[1795302804] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"509.734168ms","start":"2026-10-09T05:10:28.968064Z","end":"2026-10-09T05:10:29.477798Z","steps":["trace[1795302804] 'read index received' (duration: 458.938924ms)","trace[1795302804] 'applied index is now lower than readState.Index' (duration: 50.794063ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:10:29.477857Z","caller":"traceutil/trace.go:171","msg":"trace[1963319142] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"543.365944ms","start":"2026-10-09T05:10:28.934458Z","end":"2026-10-09T05:10:29.477824Z","steps":["trace[1963319142] 'process raft request' (duration: 492.445154ms)","trace[1963319142] 'compare' (duration: 50.761812ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:10:29.478006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.910456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:10:29.478049Z","caller":"traceutil/trace.go:171","msg":"trace[1765787744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"510.012682ms","start":"2026-10-09T05:10:28.968025Z","end":"2026-10-09T05:10:29.478038Z","steps":["trace[1765787744] 'agreement among raft nodes before linearized reading' (duration: 509.908447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:10:29.478045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:10:28.934425Z","time spent":"543.538753ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:10:29.478083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:10:28.968011Z","time spent":"510.064615ms","remote":"127.0.0.1:44704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T05:10:29.482378Z","caller":"traceutil/trace.go:171","msg":"trace[882025337] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"206.818597ms","start":"2026-10-09T05:10:29.275524Z","end":"2026-10-09T05:10:29.482342Z","steps":["trace[882025337] 'process raft request' (duration: 203.397636ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:45.12376Z","caller":"traceutil/trace.go:171","msg":"trace[10731655] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"222.407633ms","start":"2026-10-09T05:10:44.901325Z","end":"2026-10-09T05:10:45.123733Z","steps":["trace[10731655] 'read index received' (duration: 222.117586ms)","trace[10731655] 'applied index is now lower than readState.Index' (duration: 289.067µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:10:45.123842Z","caller":"traceutil/trace.go:171","msg":"trace[497323135] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"255.504653ms","start":"2026-10-09T05:10:44.868313Z","end":"2026-10-09T05:10:45.123818Z","steps":["trace[497323135] 'process raft request' (duration: 255.215006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:10:45.124027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.678929ms","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-09T05:10:45.124085Z","caller":"traceutil/trace.go:171","msg":"trace[164201049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:814; }","duration":"222.789406ms","start":"2026-10-09T05:10:44.90128Z","end":"2026-10-09T05:10:45.124069Z","steps":["trace[164201049] 'agreement among raft nodes before linearized reading' (duration: 222.618176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:10:45.124125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.218866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-09T05:10:45.124174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.090055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:10:45.124187Z","caller":"traceutil/trace.go:171","msg":"trace[872214301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:814; }","duration":"200.30208ms","start":"2026-10-09T05:10:44.923867Z","end":"2026-10-09T05:10:45.124169Z","steps":["trace[872214301] 'agreement among raft nodes before linearized reading' (duration: 200.1225ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:45.124237Z","caller":"traceutil/trace.go:171","msg":"trace[1336271504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"156.251084ms","start":"2026-10-09T05:10:44.967965Z","end":"2026-10-09T05:10:45.124216Z","steps":["trace[1336271504] 'agreement among raft nodes before linearized reading' (duration: 156.087875ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:54.281427Z","caller":"traceutil/trace.go:171","msg":"trace[647638510] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"103.580166ms","start":"2026-10-09T05:10:54.177746Z","end":"2026-10-09T05:10:54.281326Z","steps":["trace[647638510] 'process raft request' (duration: 103.428407ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:54.281429Z","caller":"traceutil/trace.go:171","msg":"trace[1052714316] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"105.903748ms","start":"2026-10-09T05:10:54.175503Z","end":"2026-10-09T05:10:54.281407Z","steps":["trace[1052714316] 'process raft request' (duration: 105.556837ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:54.544689Z","caller":"traceutil/trace.go:171","msg":"trace[1291260001] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"120.030649ms","start":"2026-10-09T05:10:54.424626Z","end":"2026-10-09T05:10:54.544657Z","steps":["trace[1291260001] 'process raft request' (duration: 115.903447ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:54.544722Z","caller":"traceutil/trace.go:171","msg":"trace[1511371893] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"119.947654ms","start":"2026-10-09T05:10:54.424751Z","end":"2026-10-09T05:10:54.544699Z","steps":["trace[1511371893] 'process raft request' (duration: 119.814366ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:54.544761Z","caller":"traceutil/trace.go:171","msg":"trace[504792119] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:954; }","duration":"119.671417ms","start":"2026-10-09T05:10:54.425068Z","end":"2026-10-09T05:10:54.544739Z","steps":["trace[504792119] 'read index received' (duration: 32.024992ms)","trace[504792119] 'applied index is now lower than readState.Index' (duration: 87.645575ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:10:54.544777Z","caller":"traceutil/trace.go:171","msg":"trace[1149977418] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"119.371269ms","start":"2026-10-09T05:10:54.425365Z","end":"2026-10-09T05:10:54.544736Z","steps":["trace[1149977418] 'process raft request' (duration: 119.240231ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:54.544891Z","caller":"traceutil/trace.go:171","msg":"trace[212177479] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"115.160371ms","start":"2026-10-09T05:10:54.429695Z","end":"2026-10-09T05:10:54.544855Z","steps":["trace[212177479] 'process raft request' (duration: 115.005162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:10:54.544927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.841127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-xmbld\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-10-09T05:10:54.544811Z","caller":"traceutil/trace.go:171","msg":"trace[1385308726] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"117.845295ms","start":"2026-10-09T05:10:54.426945Z","end":"2026-10-09T05:10:54.54479Z","steps":["trace[1385308726] 'process raft request' (duration: 117.709607ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:10:54.544959Z","caller":"traceutil/trace.go:171","msg":"trace[1179225793] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-xmbld; range_end:; response_count:1; response_revision:927; }","duration":"119.944264ms","start":"2026-10-09T05:10:54.425005Z","end":"2026-10-09T05:10:54.544949Z","steps":["trace[1179225793] 'agreement among raft nodes before linearized reading' (duration: 119.809956ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:11:38.498874Z","caller":"traceutil/trace.go:171","msg":"trace[1438462203] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"250.359547ms","start":"2026-10-09T05:11:38.24849Z","end":"2026-10-09T05:11:38.49885Z","steps":["trace[1438462203] 'process raft request' (duration: 250.216417ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:03.553568Z","caller":"traceutil/trace.go:171","msg":"trace[908163929] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"284.785845ms","start":"2026-10-09T05:12:03.268751Z","end":"2026-10-09T05:12:03.553537Z","steps":["trace[908163929] 'process raft request' (duration: 284.551327ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:03.564559Z","caller":"traceutil/trace.go:171","msg":"trace[1059156123] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1954; }","duration":"161.461773ms","start":"2026-10-09T05:12:03.403076Z","end":"2026-10-09T05:12:03.564538Z","steps":["trace[1059156123] 'read index received' (duration: 150.356088ms)","trace[1059156123] 'applied index is now lower than readState.Index' (duration: 11.104835ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:12:03.564987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.850344ms","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-09T05:12:03.564981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.870973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-281ccb85-0c4c-4c09-b3c0-11a90fbb5f78\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-09T05:12:03.56506Z","caller":"traceutil/trace.go:171","msg":"trace[269868911] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1878; }","duration":"125.984094ms","start":"2026-10-09T05:12:03.439057Z","end":"2026-10-09T05:12:03.565041Z","steps":["trace[269868911] 'agreement among raft nodes before linearized reading' (duration: 125.733546ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:03.565081Z","caller":"traceutil/trace.go:171","msg":"trace[1350569682] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-281ccb85-0c4c-4c09-b3c0-11a90fbb5f78; range_end:; response_count:1; response_revision:1878; }","duration":"162.013923ms","start":"2026-10-09T05:12:03.403043Z","end":"2026-10-09T05:12:03.565057Z","steps":["trace[1350569682] 'agreement among raft nodes before linearized reading' (duration: 161.658668ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:03.565381Z","caller":"traceutil/trace.go:171","msg":"trace[308377851] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"170.25168ms","start":"2026-10-09T05:12:03.395099Z","end":"2026-10-09T05:12:03.565351Z","steps":["trace[308377851] 'process raft request' (duration: 169.318652ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:03.677329Z","caller":"traceutil/trace.go:171","msg":"trace[1075285212] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"104.611347ms","start":"2026-10-09T05:12:03.572677Z","end":"2026-10-09T05:12:03.677288Z","steps":["trace[1075285212] 'process raft request' (duration: 104.368ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:12:03.982594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.993789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-10-09T05:12:03.982707Z","caller":"traceutil/trace.go:171","msg":"trace[1634978310] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1882; }","duration":"144.129339ms","start":"2026-10-09T05:12:03.838535Z","end":"2026-10-09T05:12:03.982665Z","steps":["trace[1634978310] 'range keys from in-memory index tree' (duration: 143.814266ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:12.203043Z","caller":"traceutil/trace.go:171","msg":"trace[944320307] linearizableReadLoop","detail":"{readStateIndex:2045; appliedIndex:2042; }","duration":"110.114289ms","start":"2026-10-09T05:12:12.092905Z","end":"2026-10-09T05:12:12.203019Z","steps":["trace[944320307] 'read index received' (duration: 9.926606ms)","trace[944320307] 'applied index is now lower than readState.Index' (duration: 100.186643ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:12:12.203191Z","caller":"traceutil/trace.go:171","msg":"trace[1940128732] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"124.848978ms","start":"2026-10-09T05:12:12.078327Z","end":"2026-10-09T05:12:12.203176Z","steps":["trace[1940128732] 'process raft request' (duration: 124.623512ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:12.203295Z","caller":"traceutil/trace.go:171","msg":"trace[655869643] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"125.176881ms","start":"2026-10-09T05:12:12.078086Z","end":"2026-10-09T05:12:12.203263Z","steps":["trace[655869643] 'process raft request' (duration: 54.324306ms)","trace[655869643] 'compare' (duration: 70.333748ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:12:12.203428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.509158ms","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-09T05:12:12.203479Z","caller":"traceutil/trace.go:171","msg":"trace[181776568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1965; }","duration":"110.572782ms","start":"2026-10-09T05:12:12.092892Z","end":"2026-10-09T05:12:12.203465Z","steps":["trace[181776568] 'agreement among raft nodes before linearized reading' (duration: 110.398619ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:38.579753Z","caller":"traceutil/trace.go:171","msg":"trace[213912897] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"114.823849ms","start":"2026-10-09T05:12:38.464904Z","end":"2026-10-09T05:12:38.579728Z","steps":["trace[213912897] 'process raft request' (duration: 114.639635ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:38.58149Z","caller":"traceutil/trace.go:171","msg":"trace[1245113871] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"113.054878ms","start":"2026-10-09T05:12:38.468408Z","end":"2026-10-09T05:12:38.581463Z","steps":["trace[1245113871] 'process raft request' (duration: 112.882765ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:13:43.035345Z","caller":"traceutil/trace.go:171","msg":"trace[1269291033] transaction","detail":"{read_only:false; response_revision:2578; number_of_response:1; }","duration":"188.54819ms","start":"2026-10-09T05:13:42.846764Z","end":"2026-10-09T05:13:43.035312Z","steps":["trace[1269291033] 'process raft request' (duration: 187.006542ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:14:15.315002Z","caller":"traceutil/trace.go:171","msg":"trace[749824233] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"163.721629ms","start":"2026-10-09T05:14:15.151253Z","end":"2026-10-09T05:14:15.314975Z","steps":["trace[749824233] 'process raft request' (duration: 163.570178ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:18:50.401599Z","caller":"traceutil/trace.go:171","msg":"trace[853874068] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"141.021407ms","start":"2026-10-09T05:18:50.260555Z","end":"2026-10-09T05:18:50.401577Z","steps":["trace[853874068] 'process raft request' (duration: 140.687038ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:18:50.439689Z","caller":"traceutil/trace.go:171","msg":"trace[1700161332] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"159.303369ms","start":"2026-10-09T05:18:50.280358Z","end":"2026-10-09T05:18:50.439661Z","steps":["trace[1700161332] 'process raft request' (duration: 157.064938ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:18:50.439867Z","caller":"traceutil/trace.go:171","msg":"trace[1181370173] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"109.354611ms","start":"2026-10-09T05:18:50.330485Z","end":"2026-10-09T05:18:50.439839Z","steps":["trace[1181370173] 'process raft request' (duration: 109.041972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:18:58.188538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.833039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-xnxlm-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-10-09T05:18:58.18862Z","caller":"traceutil/trace.go:171","msg":"trace[194433428] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-xnxlm-tls; range_end:; response_count:1; response_revision:5007; }","duration":"123.969817ms","start":"2026-10-09T05:18:58.064631Z","end":"2026-10-09T05:18:58.188601Z","steps":["trace[194433428] 'range keys from in-memory index tree' (duration: 123.52278ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:07.019132Z","caller":"traceutil/trace.go:171","msg":"trace[82881477] linearizableReadLoop","detail":"{readStateIndex:5348; appliedIndex:5347; }","duration":"193.361836ms","start":"2026-10-09T05:19:06.825745Z","end":"2026-10-09T05:19:07.019107Z","steps":["trace[82881477] 'read index received' (duration: 193.149433ms)","trace[82881477] 'applied index is now lower than readState.Index' (duration: 211.513µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:19:07.019409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.530849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-09T05:19:07.019464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.685805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-09T05:19:07.019495Z","caller":"traceutil/trace.go:171","msg":"trace[462178300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5150; }","duration":"188.667807ms","start":"2026-10-09T05:19:06.830815Z","end":"2026-10-09T05:19:07.019483Z","steps":["trace[462178300] 'agreement among raft nodes before linearized reading' (duration: 188.420072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:19:07.019518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.115934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T05:19:07.019565Z","caller":"traceutil/trace.go:171","msg":"trace[890200425] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5150; }","duration":"193.834364ms","start":"2026-10-09T05:19:06.825709Z","end":"2026-10-09T05:19:07.019543Z","steps":["trace[890200425] 'agreement among raft nodes before linearized reading' (duration: 193.530126ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:07.019598Z","caller":"traceutil/trace.go:171","msg":"trace[1901584864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5150; }","duration":"121.244432ms","start":"2026-10-09T05:19:06.898334Z","end":"2026-10-09T05:19:07.019578Z","steps":["trace[1901584864] 'agreement among raft nodes before linearized reading' (duration: 121.020068ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:19:07.01961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.694499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-10-09T05:19:07.019698Z","caller":"traceutil/trace.go:171","msg":"trace[1065475390] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5150; }","duration":"154.824776ms","start":"2026-10-09T05:19:06.864851Z","end":"2026-10-09T05:19:07.019675Z","steps":["trace[1065475390] 'agreement among raft nodes before linearized reading' (duration: 154.513138ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:07.019231Z","caller":"traceutil/trace.go:171","msg":"trace[313624690] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"235.69301ms","start":"2026-10-09T05:19:06.783497Z","end":"2026-10-09T05:19:07.01919Z","steps":["trace[313624690] 'process raft request' (duration: 235.477727ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:11.383884Z","caller":"traceutil/trace.go:171","msg":"trace[1357773355] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"312.459458ms","start":"2026-10-09T05:19:11.071398Z","end":"2026-10-09T05:19:11.383857Z","steps":["trace[1357773355] 'process raft request' (duration: 310.429387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:19:11.384029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:19:11.071382Z","time spent":"312.571215ms","remote":"127.0.0.1:44980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T05:19:11.579044Z","caller":"traceutil/trace.go:171","msg":"trace[557678633] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"506.651648ms","start":"2026-10-09T05:19:11.072366Z","end":"2026-10-09T05:19:11.579018Z","steps":["trace[557678633] 'process raft request' (duration: 414.311282ms)","trace[557678633] 'compare' (duration: 92.179696ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:19:11.579048Z","caller":"traceutil/trace.go:171","msg":"trace[2099616417] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"506.546072ms","start":"2026-10-09T05:19:11.072486Z","end":"2026-10-09T05:19:11.579032Z","steps":["trace[2099616417] 'process raft request' (duration: 506.482098ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:11.579167Z","caller":"traceutil/trace.go:171","msg":"trace[43714268] linearizableReadLoop","detail":"{readStateIndex:5375; appliedIndex:5372; }","duration":"353.407391ms","start":"2026-10-09T05:19:11.225751Z","end":"2026-10-09T05:19:11.579158Z","steps":["trace[43714268] 'read index received' (duration: 156.092526ms)","trace[43714268] 'applied index is now lower than readState.Index' (duration: 197.313905ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:19:11.57917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:19:11.072353Z","time spent":"506.753094ms","remote":"127.0.0.1:44980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:19:11.579292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.544218ms","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-09T05:19:11.579312Z","caller":"traceutil/trace.go:171","msg":"trace[187615496] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5177; }","duration":"353.608233ms","start":"2026-10-09T05:19:11.225699Z","end":"2026-10-09T05:19:11.579307Z","steps":["trace[187615496] 'agreement among raft nodes before linearized reading' (duration: 353.506087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:19:11.579326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:19:11.225672Z","time spent":"353.650405ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-09T05:19:11.579493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.677493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-09T05:19:11.579587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:19:11.072439Z","time spent":"506.672909ms","remote":"127.0.0.1:44980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T05:19:11.579637Z","caller":"traceutil/trace.go:171","msg":"trace[281419244] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5177; }","duration":"285.854493ms","start":"2026-10-09T05:19:11.293761Z","end":"2026-10-09T05:19:11.579615Z","steps":["trace[281419244] 'agreement among raft nodes before linearized reading' (duration: 285.539024ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:11.579252Z","caller":"traceutil/trace.go:171","msg":"trace[956558852] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"274.145525ms","start":"2026-10-09T05:19:11.305076Z","end":"2026-10-09T05:19:11.579221Z","steps":["trace[956558852] 'process raft request' (duration: 273.914791ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:11.779438Z","caller":"traceutil/trace.go:171","msg":"trace[874988697] linearizableReadLoop","detail":"{readStateIndex:5378; appliedIndex:5377; }","duration":"113.799864ms","start":"2026-10-09T05:19:11.66561Z","end":"2026-10-09T05:19:11.77941Z","steps":["trace[874988697] 'read index received' (duration: 44.706515ms)","trace[874988697] 'applied index is now lower than readState.Index' (duration: 69.091579ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:19:11.779545Z","caller":"traceutil/trace.go:171","msg":"trace[1933808353] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"115.493185ms","start":"2026-10-09T05:19:11.663972Z","end":"2026-10-09T05:19:11.779465Z","steps":["trace[1933808353] 'process raft request' (duration: 46.329952ms)","trace[1933808353] 'compare' (duration: 68.736838ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:19:11.779617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.50424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:19:11.779658Z","caller":"traceutil/trace.go:171","msg":"trace[1858908507] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5179; }","duration":"132.591205ms","start":"2026-10-09T05:19:11.647057Z","end":"2026-10-09T05:19:11.779648Z","steps":["trace[1858908507] 'agreement among raft nodes before linearized reading' (duration: 132.50623ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:11.806948Z","caller":"traceutil/trace.go:171","msg":"trace[1430258748] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"134.33341ms","start":"2026-10-09T05:19:11.672595Z","end":"2026-10-09T05:19:11.806929Z","steps":["trace[1430258748] 'process raft request' (duration: 134.187391ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:11.807178Z","caller":"traceutil/trace.go:171","msg":"trace[1214398410] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"134.322449ms","start":"2026-10-09T05:19:11.672841Z","end":"2026-10-09T05:19:11.807163Z","steps":["trace[1214398410] 'process raft request' (duration: 134.035942ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:11.807308Z","caller":"traceutil/trace.go:171","msg":"trace[1280056572] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"134.428785ms","start":"2026-10-09T05:19:11.672857Z","end":"2026-10-09T05:19:11.807286Z","steps":["trace[1280056572] 'process raft request' (duration: 134.259055ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:11.807374Z","caller":"traceutil/trace.go:171","msg":"trace[1622737899] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"133.974608ms","start":"2026-10-09T05:19:11.673381Z","end":"2026-10-09T05:19:11.807356Z","steps":["trace[1622737899] 'process raft request' (duration: 133.83173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:19:11.807468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.435724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-556fa82e-451c-49b7-b409-45d2a9535803\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-10-09T05:19:11.807573Z","caller":"traceutil/trace.go:171","msg":"trace[1929126704] range","detail":"{range_begin:/registry/persistentvolumes/pvc-556fa82e-451c-49b7-b409-45d2a9535803; range_end:; response_count:1; response_revision:5183; }","duration":"138.579782ms","start":"2026-10-09T05:19:11.668973Z","end":"2026-10-09T05:19:11.807553Z","steps":["trace[1929126704] 'agreement among raft nodes before linearized reading' (duration: 138.315226ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:38.803974Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2877} {"level":"info","ts":"2026-10-09T05:19:39.001246Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2877,"took":"195.663393ms","hash":2282259553,"current-db-size-bytes":53538816,"current-db-size":"54 MB","current-db-size-in-use-bytes":29016064,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-09T05:19:39.001309Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2282259553,"revision":2877,"compact-revision":-1} {"level":"warn","ts":"2026-10-09T05:23:07.871786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.762111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:23:07.872013Z","caller":"traceutil/trace.go:171","msg":"trace[640154716] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:6936; }","duration":"120.041091ms","start":"2026-10-09T05:23:07.751947Z","end":"2026-10-09T05:23:07.871988Z","steps":["trace[640154716] 'count revisions from in-memory index tree' (duration: 119.689001ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:24:38.810284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5466} {"level":"info","ts":"2026-10-09T05:24:38.90944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5466,"took":"97.632589ms","hash":2859072882,"current-db-size-bytes":53538816,"current-db-size":"54 MB","current-db-size-in-use-bytes":18857984,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-09T05:24:38.909505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2859072882,"revision":5466,"compact-revision":2877}